Dec 13 18:19:03 aura go-librespot[1840]: time="2025-12-13T18:19:03Z" level=debug msg="fetched chunk 13/16, size: 524288" uri="spotify:track:5SxkdsY1ufZzoq9iXceLw9" Dec 13 18:19:10 aura volumio[1419]: info: CorePlayQueue::getTrack 32 Dec 13 18:19:10 aura volumio[1419]: info: CorePlayQueue::getTrack 33 Dec 13 18:19:10 aura volumio[1419]: info: Prefetching next song Dec 13 18:19:10 aura volumio[1419]: info: [1765649950358] ControllerSpotify::prefetch Dec 13 18:19:10 aura volumio[1419]: info: Sending Spotify command with payload to local API: /player/add_to_queue Dec 13 18:19:10 aura go-librespot[1840]: time="2025-12-13T18:19:10Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 13 18:19:10 aura go-librespot[1840]: time="2025-12-13T18:19:10Z" level=trace msg="scheduling prefetch in 47s" Dec 13 18:19:13 aura go-librespot[1840]: time="2025-12-13T18:19:13Z" level=trace msg="sent dealer ping" Dec 13 18:19:13 aura go-librespot[1840]: time="2025-12-13T18:19:13Z" level=trace msg="received dealer pong" Dec 13 18:19:14 aura volumio[1419]: info: CoreStateMachine::startPlaybackTimer Dec 13 18:19:14 aura volumio[1419]: info: CorePlayQueue::getTrack 33 Dec 13 18:19:15 aura volumio[1419]: info: CoreStateMachine::pushState Dec 13 18:19:15 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 18:19:15 aura volumio[1419]: info: CoreCommandRouter::volumioPushState Dec 13 18:19:15 aura go-librespot[1840]: time="2025-12-13T18:19:15Z" level=debug msg="fetched chunk 14/16, size: 524288" uri="spotify:track:5SxkdsY1ufZzoq9iXceLw9" Dec 13 18:19:15 aura volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Dec 13 18:19:15 aura volumio[1419]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 13 18:19:15 aura sudo[7635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 13 18:19:15 aura sudo[7635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:19:15 aura sudo[7635]: pam_unix(sudo:session): session closed for user root Dec 13 18:19:15 aura volumio[1419]: info: peppymeterbasic Daemon Started Dec 13 18:19:21 aura volumio[1419]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.12 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/BP3A.251105.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::volumioGetVisibleSources Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::volumioGetState Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 13 18:19:21 aura volumio[1419]: info: Received Get System Info Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 18:19:21 aura volumio[1419]: info: Discovery: Getting this device information Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::volumioGetState Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::volumioGetState Dec 13 18:19:21 aura volumio[1419]: info: Listing playlists Dec 13 18:19:21 aura volumio[1419]: info: CoreCommandRouter::volumioGetQueue Dec 13 18:19:21 aura volumio[1419]: info: CoreStateMachine::getQueue Dec 13 18:19:21 aura volumio[1419]: info: CorePlayQueue::getQueue Dec 13 18:19:26 aura volumio[1419]: info: CoreCommandRouter::volumioNext Dec 13 18:19:26 aura volumio[1419]: info: CoreStateMachine::next Dec 13 18:19:26 aura volumio[1419]: info: Spotify next Dec 13 18:19:26 aura volumio[1419]: info: Sending Spotify command to local API: /player/next Dec 13 18:19:26 aura go-librespot[1840]: time="2025-12-13T18:19:26Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:19:26 aura go-librespot[1840]: time="2025-12-13T18:19:26Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 13 18:19:26 aura go-librespot[1840]: time="2025-12-13T18:19:26Z" level=trace msg="emitting websocket event: will_play" Dec 13 18:19:26 aura volumio[1419]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1E4osfZT46NjxN","uri":"spotify:track:7iDa6hUg2VgEL1o1HjmfBn","play_origin":"playlist"}} Dec 13 18:19:26 aura go-librespot[1840]: time="2025-12-13T18:19:26Z" level=debug msg="selected format OGG_VORBIS_320 (b262bc7fe843d91d2639a622d1ab50899226ecf2)" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:19:26 aura go-librespot[1840]: time="2025-12-13T18:19:26Z" level=debug msg="requested aes key for file b262bc7fe843d91d2639a622d1ab50899226ecf2, gid: 7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:19:27 aura go-librespot[1840]: time="2025-12-13T18:19:27Z" level=trace msg="found 2 cdn urls" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:19:27 aura go-librespot[1840]: time="2025-12-13T18:19:27Z" level=debug msg="fetched first chunk of 15, total size is 7484268 bytes" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:19:27 aura go-librespot[1840]: time="2025-12-13T18:19:27Z" level=info msg="loaded track \"Meant to Be (feat. Florida Georgia Line)\" (paused: false, position: 0ms, duration: 163870ms, prefetched: false)" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:19:27 aura go-librespot[1840]: time="2025-12-13T18:19:27Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 13 18:19:27 aura go-librespot[1840]: time="2025-12-13T18:19:27Z" level=trace msg="scheduling prefetch in 134s" Dec 13 18:19:27 aura go-librespot[1840]: time="2025-12-13T18:19:27Z" level=trace msg="emitting websocket event: metadata" Dec 13 18:19:27 aura volumio[1419]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7iDa6hUg2VgEL1o1HjmfBn","name":"Meant to Be (feat. Florida Georgia Line)","artist_names":["Bebe Rexha","Florida Georgia Line"],"album_name":"All Your Fault: Pt. 2","album_cover_url":"https://i.scdn.co/image/ab67616d00001e021ba5682505dd6e2592b16e41","position":0,"duration":163870,"release_date":"year:2017 month:8 day:11","track_number":6,"disc_number":1}} Dec 13 18:19:27 aura go-librespot[1840]: time="2025-12-13T18:19:27Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 13 18:19:27 aura go-librespot[1840]: time="2025-12-13T18:19:27Z" level=trace msg="emitting websocket event: playing" Dec 13 18:19:27 aura volumio[1419]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:37i9dQZF1E4osfZT46NjxN","uri":"spotify:track:7iDa6hUg2VgEL1o1HjmfBn","resume":false,"play_origin":"playlist"}} Dec 13 18:19:27 aura volumio[1419]: SPOTIFY: PUSH STATE SPOTIFY Dec 13 18:19:27 aura volumio[1419]: SPOTIFY: {"status":"play","service":"spop","title":"Meant to Be (feat. Florida Georgia Line)","artist":"Bebe Rexha, Florida Georgia Line","album":"All Your Fault: Pt. 2","albumart":"https://i.scdn.co/image/ab67616d00001e021ba5682505dd6e2592b16e41","uri":"spotify:track:7iDa6hUg2VgEL1o1HjmfBn","trackType":"spotify","seek":0,"duration":163,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 13 18:19:27 aura volumio[1419]: info: CoreCommandRouter::servicePushState Dec 13 18:19:27 aura volumio[1419]: info: CoreStateMachine::pushState Dec 13 18:19:27 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 18:19:27 aura volumio[1419]: info: CoreCommandRouter::volumioPushState Dec 13 18:19:27 aura volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Dec 13 18:19:27 aura volumio[1419]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 13 18:19:27 aura go-librespot[1840]: time="2025-12-13T18:19:27Z" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:19:27 aura sudo[7656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 13 18:19:27 aura sudo[7656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:19:27 aura sudo[7656]: pam_unix(sudo:session): session closed for user root Dec 13 18:19:27 aura volumio[1419]: info: peppymeterbasic Daemon Started Dec 13 18:19:27 aura go-librespot[1840]: time="2025-12-13T18:19:27Z" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:19:27 aura go-librespot[1840]: time="2025-12-13T18:19:27Z" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:19:27 aura volumio[1419]: SPOTIFY: PUSH STATE SPOTIFY Dec 13 18:19:27 aura volumio[1419]: SPOTIFY: {"status":"play","service":"spop","title":"Meant to Be (feat. Florida Georgia Line)","artist":"Bebe Rexha, Florida Georgia Line","album":"All Your Fault: Pt. 2","albumart":"https://i.scdn.co/image/ab67616d00001e021ba5682505dd6e2592b16e41","uri":"spotify:track:7iDa6hUg2VgEL1o1HjmfBn","trackType":"spotify","seek":0,"duration":163,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 13 18:19:27 aura volumio[1419]: info: CoreCommandRouter::servicePushState Dec 13 18:19:27 aura volumio[1419]: info: CoreStateMachine::pushState Dec 13 18:19:27 aura volumio[1419]: info: CoreCommandRouter::volumioPushState Dec 13 18:19:27 aura volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Dec 13 18:19:27 aura volumio[1419]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 13 18:19:27 aura sudo[7659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 13 18:19:27 aura sudo[7659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:19:27 aura sudo[7659]: pam_unix(sudo:session): session closed for user root Dec 13 18:19:27 aura volumio[1419]: info: peppymeterbasic Daemon Started Dec 13 18:19:32 aura systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 13 18:19:33 aura systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 13 18:19:33 aura systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 13 18:19:36 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 13 18:19:36 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 13 18:19:36 aura sudo[7703]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 13 18:19:36 aura sudo[7703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:19:36 aura sudo[7705]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 13 18:19:36 aura sudo[7703]: pam_unix(sudo:session): session closed for user root Dec 13 18:19:36 aura sudo[7705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:19:36 aura sudo[7705]: pam_unix(sudo:session): session closed for user root Dec 13 18:19:36 aura volumio[1419]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.12 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/BP3A.251105.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Dec 13 18:19:37 aura sudo[7709]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 13 18:19:37 aura sudo[7709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:19:37 aura sudo[7709]: pam_unix(sudo:session): session closed for user root Dec 13 18:19:37 aura sudo[7711]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 13 18:19:37 aura sudo[7711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:19:37 aura sudo[7711]: pam_unix(sudo:session): session closed for user root Dec 13 18:19:37 aura volumio[1419]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.12 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/BP3A.251105.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::volumioGetVisibleSources Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::volumioGetState Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 13 18:19:37 aura volumio[1419]: info: Received Get System Info Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 18:19:37 aura volumio[1419]: info: Discovery: Getting this device information Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::volumioGetState Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::volumioGetState Dec 13 18:19:37 aura volumio[1419]: info: Listing playlists Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 13 18:19:37 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 13 18:19:38 aura go-librespot[1840]: time="2025-12-13T18:19:38Z" level=debug msg="fetched chunk 4/14, size: 524288" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:19:39 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 13 18:19:39 aura volumio[1419]: info: Received Get System Info Dec 13 18:19:39 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 18:19:39 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 18:19:39 aura volumio[1419]: info: Discovery: Getting this device information Dec 13 18:19:39 aura volumio[1419]: info: CoreCommandRouter::volumioGetState Dec 13 18:19:39 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 18:19:39 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 13 18:19:39 aura volumio[1419]: info: CoreCommandRouter::volumioGetQueue Dec 13 18:19:39 aura volumio[1419]: info: CoreStateMachine::getQueue Dec 13 18:19:39 aura volumio[1419]: info: CorePlayQueue::getQueue Dec 13 18:19:39 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 13 18:19:39 aura volumio[1419]: info: Received Get System Info Dec 13 18:19:39 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 18:19:39 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 18:19:39 aura volumio[1419]: info: Discovery: Getting this device information Dec 13 18:19:39 aura volumio[1419]: info: CoreCommandRouter::volumioGetState Dec 13 18:19:39 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 18:19:43 aura go-librespot[1840]: time="2025-12-13T18:19:43Z" level=trace msg="sent dealer ping" Dec 13 18:19:43 aura go-librespot[1840]: time="2025-12-13T18:19:43Z" level=trace msg="received dealer pong" Dec 13 18:19:46 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 18:19:46 aura volumio[1419]: info: CURURI: albums:// Dec 13 18:19:46 aura volumio[1419]: info: listAlbums - loading Albums from cache Dec 13 18:19:46 aura volumio[1419]: info: Preload queue cleared Dec 13 18:19:47 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 13 18:19:49 aura go-librespot[1840]: time="2025-12-13T18:19:49Z" level=debug msg="fetched chunk 5/14, size: 524288" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:19:55 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 13 18:19:55 aura volumio[1419]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 13 18:19:55 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 13 18:19:55 aura volumio[1419]: info: Received Get System Version Dec 13 18:19:55 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 18:19:55 aura volumio[1419]: info: Received Get System Info Dec 13 18:19:55 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 18:19:55 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 18:19:55 aura volumio[1419]: info: Discovery: Getting this device information Dec 13 18:19:55 aura volumio[1419]: info: CoreCommandRouter::volumioGetState Dec 13 18:19:55 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 18:19:57 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 18:19:57 aura volumio[1419]: info: CURURI: artists:// Dec 13 18:19:57 aura volumio[1419]: info: Preload queue cleared Dec 13 18:19:59 aura go-librespot[1840]: time="2025-12-13T18:19:59Z" level=debug msg="fetched chunk 6/14, size: 524288" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:20:11 aura go-librespot[1840]: time="2025-12-13T18:20:11Z" level=debug msg="fetched chunk 7/14, size: 524288" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:20:13 aura go-librespot[1840]: time="2025-12-13T18:20:13Z" level=trace msg="sent dealer ping" Dec 13 18:20:13 aura go-librespot[1840]: time="2025-12-13T18:20:13Z" level=trace msg="received dealer pong" Dec 13 18:20:22 aura go-librespot[1840]: time="2025-12-13T18:20:22Z" level=debug msg="fetched chunk 8/14, size: 524288" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:20:25 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 18:20:25 aura volumio[1419]: info: CURURI: artists://Royal%20Blood Dec 13 18:20:25 aura volumio[1419]: info: Preload queue cleared Dec 13 18:20:25 aura volumio[1419]: info: Preload queue cleared Dec 13 18:20:25 aura volumio[1419]: info: Preloading song: music-library/NAS/Music/Royal Blood - Royal Blood/01. Out Of The Black.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Preloading song: music-library/NAS/Music/Royal Blood - Royal Blood/02. Come On Over.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Preloading song: music-library/NAS/Music/Royal Blood - Royal Blood/03. Figure It Out.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Preloading song: music-library/NAS/Music/Royal Blood - Royal Blood/04. You Can Be So Cruel.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Preloading song: music-library/NAS/Music/Royal Blood - Royal Blood/05. Blood Hands.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Preloading song: music-library/NAS/Music/Royal Blood - Royal Blood/06. Little Monster.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Preloading song: music-library/NAS/Music/Royal Blood - Royal Blood/07. Loose Change.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Preloading song: music-library/NAS/Music/Royal Blood - Royal Blood/08. Careless.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Preloading song: music-library/NAS/Music/Royal Blood - Royal Blood/09. Ten Tonne Skeleton.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Preloading song: music-library/NAS/Music/Royal Blood - Royal Blood/10. Better Strangers.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Exploding uri music-library/NAS/Music/Royal Blood - Royal Blood/01. Out Of The Black.mp3 in service mpd Dec 13 18:20:25 aura volumio[1419]: info: Exploding uri music-library/NAS/Music/Royal Blood - Royal Blood/02. Come On Over.mp3 in service mpd Dec 13 18:20:25 aura volumio[1419]: info: ALBUMART /albumart?cacheid=333&web=Royal%20Blood/Royal%20Blood/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FRoyal%20Blood%20-%20Royal%20Blood%2F01.%20Out%20Of%20The%20Black.mp3&metadata=false Dec 13 18:20:25 aura volumio[1419]: info: URI /mnt/NAS/Music/Royal Blood - Royal Blood/01. Out Of The Black.mp3 Dec 13 18:20:25 aura volumio[1419]: info: ALBUMART /albumart?cacheid=333&web=Royal%20Blood/Royal%20Blood/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FRoyal%20Blood%20-%20Royal%20Blood%2F02.%20Come%20On%20Over.mp3&metadata=false Dec 13 18:20:25 aura volumio[1419]: info: URI /mnt/NAS/Music/Royal Blood - Royal Blood/02. Come On Over.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Exploding uri music-library/NAS/Music/Royal Blood - Royal Blood/03. Figure It Out.mp3 in service mpd Dec 13 18:20:25 aura volumio[1419]: info: ALBUMART /albumart?cacheid=333&web=Royal%20Blood/Royal%20Blood/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FRoyal%20Blood%20-%20Royal%20Blood%2F03.%20Figure%20It%20Out.mp3&metadata=false Dec 13 18:20:25 aura volumio[1419]: info: URI /mnt/NAS/Music/Royal Blood - Royal Blood/03. Figure It Out.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Exploding uri music-library/NAS/Music/Royal Blood - Royal Blood/04. You Can Be So Cruel.mp3 in service mpd Dec 13 18:20:25 aura volumio[1419]: info: ALBUMART /albumart?cacheid=333&web=Royal%20Blood/Royal%20Blood/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FRoyal%20Blood%20-%20Royal%20Blood%2F04.%20You%20Can%20Be%20So%20Cruel.mp3&metadata=false Dec 13 18:20:25 aura volumio[1419]: info: URI /mnt/NAS/Music/Royal Blood - Royal Blood/04. You Can Be So Cruel.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Exploding uri music-library/NAS/Music/Royal Blood - Royal Blood/05. Blood Hands.mp3 in service mpd Dec 13 18:20:25 aura volumio[1419]: info: ALBUMART /albumart?cacheid=333&web=Royal%20Blood/Royal%20Blood/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FRoyal%20Blood%20-%20Royal%20Blood%2F05.%20Blood%20Hands.mp3&metadata=false Dec 13 18:20:25 aura volumio[1419]: info: URI /mnt/NAS/Music/Royal Blood - Royal Blood/05. Blood Hands.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Exploding uri music-library/NAS/Music/Royal Blood - Royal Blood/06. Little Monster.mp3 in service mpd Dec 13 18:20:25 aura volumio[1419]: info: ALBUMART /albumart?cacheid=333&web=Royal%20Blood/Royal%20Blood/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FRoyal%20Blood%20-%20Royal%20Blood%2F06.%20Little%20Monster.mp3&metadata=false Dec 13 18:20:25 aura volumio[1419]: info: URI /mnt/NAS/Music/Royal Blood - Royal Blood/06. Little Monster.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Exploding uri music-library/NAS/Music/Royal Blood - Royal Blood/07. Loose Change.mp3 in service mpd Dec 13 18:20:25 aura volumio[1419]: info: ALBUMART /albumart?cacheid=333&web=Royal%20Blood/Royal%20Blood/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FRoyal%20Blood%20-%20Royal%20Blood%2F07.%20Loose%20Change.mp3&metadata=false Dec 13 18:20:25 aura volumio[1419]: info: URI /mnt/NAS/Music/Royal Blood - Royal Blood/07. Loose Change.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Exploding uri music-library/NAS/Music/Royal Blood - Royal Blood/08. Careless.mp3 in service mpd Dec 13 18:20:25 aura volumio[1419]: info: ALBUMART /albumart?cacheid=333&web=Royal%20Blood/Royal%20Blood/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FRoyal%20Blood%20-%20Royal%20Blood%2F08.%20Careless.mp3&metadata=false Dec 13 18:20:25 aura volumio[1419]: info: URI /mnt/NAS/Music/Royal Blood - Royal Blood/08. Careless.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Exploding uri music-library/NAS/Music/Royal Blood - Royal Blood/09. Ten Tonne Skeleton.mp3 in service mpd Dec 13 18:20:25 aura volumio[1419]: info: ALBUMART /albumart?cacheid=333&web=Royal%20Blood/Royal%20Blood/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FRoyal%20Blood%20-%20Royal%20Blood%2F09.%20Ten%20Tonne%20Skeleton.mp3&metadata=false Dec 13 18:20:25 aura volumio[1419]: info: URI /mnt/NAS/Music/Royal Blood - Royal Blood/09. Ten Tonne Skeleton.mp3 Dec 13 18:20:25 aura volumio[1419]: info: Exploding uri music-library/NAS/Music/Royal Blood - Royal Blood/10. Better Strangers.mp3 in service mpd Dec 13 18:20:25 aura volumio[1419]: info: ALBUMART /albumart?cacheid=333&web=Royal%20Blood/Royal%20Blood/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FRoyal%20Blood%20-%20Royal%20Blood%2F10.%20Better%20Strangers.mp3&metadata=false Dec 13 18:20:25 aura volumio[1419]: info: URI /mnt/NAS/Music/Royal Blood - Royal Blood/10. Better Strangers.mp3 Dec 13 18:20:31 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 18:20:31 aura volumio[1419]: info: CURURI: artists://Royal%20Blood/Royal%20Blood Dec 13 18:20:31 aura volumio[1419]: info: Preload queue cleared Dec 13 18:20:34 aura go-librespot[1840]: time="2025-12-13T18:20:34Z" level=debug msg="fetched chunk 9/14, size: 524288" uri="spotify:track:7iDa6hUg2VgEL1o1HjmfBn" Dec 13 18:20:34 aura volumio[1419]: info: Preload queue cleared Dec 13 18:20:34 aura volumio[1419]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::ClearQueue Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::stop Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::serviceStop Dec 13 18:20:34 aura volumio[1419]: info: CoreCommandRouter::serviceStop Dec 13 18:20:34 aura volumio[1419]: info: Spotify Stop Dec 13 18:20:34 aura volumio[1419]: SPOTIFY: SPOTIFY STOP Dec 13 18:20:34 aura volumio[1419]: SPOTIFY: {"status":"play","title":"Meant to Be (feat. Florida Georgia Line)","artist":"Bebe Rexha, Florida Georgia Line","album":"All Your Fault: Pt. 2","albumart":"https://i.scdn.co/image/ab67616d00001e021ba5682505dd6e2592b16e41","uri":"spotify:track:7iDa6hUg2VgEL1o1HjmfBn","trackType":"spotify","codec":"ogg","seek":0,"duration":163,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":61,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Dec 13 18:20:34 aura volumio[1419]: info: Sending Spotify command to local API: /player/pause Dec 13 18:20:34 aura volumio[1419]: info: CorePlayQueue::clearPlayQueue Dec 13 18:20:34 aura volumio[1419]: info: CorePlayQueue::saveQueue Dec 13 18:20:34 aura volumio[1419]: info: CoreCommandRouter::volumioPushQueue Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::addQueueItems Dec 13 18:20:34 aura volumio[1419]: info: CorePlayQueue::addQueueItems Dec 13 18:20:34 aura volumio[1419]: info: Preload queue cleared Dec 13 18:20:34 aura volumio[1419]: info: Adding Item to queue: artists://Royal%20Blood/Royal%20Blood Dec 13 18:20:34 aura volumio[1419]: info: Exploding uri artists://Royal%20Blood/Royal%20Blood in service mpd Dec 13 18:20:34 aura go-librespot[1840]: time="2025-12-13T18:20:34Z" level=debug msg="pause track at 67174ms" Dec 13 18:20:34 aura volumio[1419]: info: CoreCommandRouter::volumioPushQueue Dec 13 18:20:34 aura volumio[1419]: info: CorePlayQueue::saveQueue Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::updateTrackBlock Dec 13 18:20:34 aura volumio[1419]: info: CorePlayQueue::getTrackBlock Dec 13 18:20:34 aura volumio[1419]: info: CoreCommandRouter::volumioPlay Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::play index 0 Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::stop Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::stPlaybackTimer Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::updateTrackBlock Dec 13 18:20:34 aura volumio[1419]: info: CorePlayQueue::getTrackBlock Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::pushState Dec 13 18:20:34 aura volumio[1419]: info: CorePlayQueue::getTrack 33 Dec 13 18:20:34 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 18:20:34 aura volumio[1419]: info: CoreCommandRouter::volumioPushState Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::serviceStop Dec 13 18:20:34 aura volumio[1419]: info: CorePlayQueue::getTrack 33 Dec 13 18:20:34 aura volumio[1419]: info: ControllerMpd::stop Dec 13 18:20:34 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 18:20:34 aura volumio[1419]: info: sendMpdCommand stop took 11 milliseconds Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::play index undefined Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 18:20:34 aura volumio[1419]: info: CorePlayQueue::getTrack 0 Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::startPlaybackTimer Dec 13 18:20:34 aura volumio[1419]: info: CorePlayQueue::getTrack 0 Dec 13 18:20:34 aura volumio[1419]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Royal Blood - Royal Blood/01. Out Of The Black.mp3 Dec 13 18:20:34 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 18:20:34 aura volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Dec 13 18:20:34 aura volumio[1419]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 13 18:20:34 aura go-librespot[1840]: time="2025-12-13T18:20:34Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 13 18:20:34 aura volumio[1419]: info: sendMpdCommand stop took 54 milliseconds Dec 13 18:20:34 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand clear Dec 13 18:20:34 aura volumio[1419]: info: Dec 13 18:20:34 aura volumio[1419]: ---------------------------- MPD announces system playlist update Dec 13 18:20:34 aura volumio[1419]: info: Ignoring MPD Status Update Dec 13 18:20:34 aura volumio[1419]: info: sendMpdCommand clear took 14 milliseconds Dec 13 18:20:34 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Royal Blood - Royal Blood/01. Out Of The Black.mp3" Dec 13 18:20:34 aura go-librespot[1840]: time="2025-12-13T18:20:34Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 13 18:20:34 aura go-librespot[1840]: time="2025-12-13T18:20:34Z" level=trace msg="emitting websocket event: paused" Dec 13 18:20:34 aura volumio[1419]: info: Dec 13 18:20:34 aura volumio[1419]: ---------------------------- MPD announces system playlist update Dec 13 18:20:34 aura volumio[1419]: info: Ignoring MPD Status Update Dec 13 18:20:34 aura volumio[1419]: error: updateQueue error: null Dec 13 18:20:34 aura volumio[1419]: info: Dec 13 18:20:34 aura volumio[1419]: ---------------------------- MPD announces system playlist update Dec 13 18:20:34 aura volumio[1419]: info: Ignoring MPD Status Update Dec 13 18:20:34 aura volumio[1419]: info: ------------------------------ 27ms Dec 13 18:20:34 aura volumio[1419]: info: sendMpdCommand add "NAS/Music/Royal Blood - Royal Blood/01. Out Of The Black.mp3" took 22 milliseconds Dec 13 18:20:34 aura volumio[1419]: info: ------------------------------ 19ms Dec 13 18:20:34 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand play Dec 13 18:20:34 aura volumio[1419]: info: Dec 13 18:20:34 aura volumio[1419]: ---------------------------- MPD announces system playlist update Dec 13 18:20:34 aura volumio[1419]: info: Ignoring MPD Status Update Dec 13 18:20:34 aura volumio[1419]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1E4osfZT46NjxN","uri":"spotify:track:7iDa6hUg2VgEL1o1HjmfBn","play_origin":"playlist"}} Dec 13 18:20:34 aura volumio[1419]: info: Spotify is playing in volatile mode Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 18:20:34 aura volumio[1419]: SPOTIFY: UNSET VOLATILE Dec 13 18:20:34 aura volumio[1419]: SPOTIFY: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":61,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":false,"repeat":null,"repeatSingle":false,"consume":false} Dec 13 18:20:34 aura volumio[1419]: SPOTIFY: PUSH STATE SPOTIFY Dec 13 18:20:34 aura volumio[1419]: SPOTIFY: {"status":"pause","service":"spop","title":"Meant to Be (feat. Florida Georgia Line)","artist":"Bebe Rexha, Florida Georgia Line","album":"All Your Fault: Pt. 2","albumart":"https://i.scdn.co/image/ab67616d00001e021ba5682505dd6e2592b16e41","uri":"spotify:track:7iDa6hUg2VgEL1o1HjmfBn","trackType":"spotify","seek":66000,"duration":163,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 13 18:20:34 aura volumio[1419]: info: CoreCommandRouter::servicePushState Dec 13 18:20:34 aura volumio[1419]: info: CoreStateMachine::pushState Dec 13 18:20:34 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 18:20:34 aura volumio[1419]: info: CoreCommandRouter::volumioPushState Dec 13 18:20:34 aura volumio[1419]: info: ------------------------------ 26ms Dec 13 18:20:34 aura volumio[1419]: info: sendMpdCommand play took 21 milliseconds Dec 13 18:20:34 aura volumio[1419]: info: ------------------------------ 21ms Dec 13 18:20:34 aura volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Dec 13 18:20:34 aura volumio[1419]: info: PeppyMeterBasic ---peppymeterbasic status pause Dec 13 18:20:34 aura sudo[7852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 13 18:20:34 aura sudo[7852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:20:34 aura volumio[1419]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 13 18:20:34 aura sudo[7873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 13 18:20:34 aura sudo[7873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:20:34 aura systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 13 18:20:34 aura systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 13 18:20:34 aura systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Dec 13 18:20:34 aura systemd[1]: peppymeterbasic.service: Consumed 11min 25.044s CPU time. Dec 13 18:20:34 aura sudo[7873]: pam_unix(sudo:session): session closed for user root Dec 13 18:20:34 aura sudo[7852]: pam_unix(sudo:session): session closed for user root Dec 13 18:20:35 aura volumio[1419]: info: peppymeterbasic Daemon Stop Dec 13 18:20:35 aura volumio[1419]: info: peppymeterbasic Daemon Stop Dec 13 18:20:35 aura volumio[1419]: info: Dec 13 18:20:35 aura volumio[1419]: ---------------------------- MPD announces state update: player Dec 13 18:20:35 aura volumio[1419]: info: ControllerMpd::getState Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Dec 13 18:20:35 aura volumio[1419]: info: Dec 13 18:20:35 aura volumio[1419]: ---------------------------- MPD announces state update: player Dec 13 18:20:35 aura volumio[1419]: info: ControllerMpd::getState Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Dec 13 18:20:35 aura volumio[1419]: info: Dec 13 18:20:35 aura volumio[1419]: ---------------------------- MPD announces state update: player Dec 13 18:20:35 aura volumio[1419]: info: ControllerMpd::getState Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Dec 13 18:20:35 aura volumio[1419]: info: Dec 13 18:20:35 aura volumio[1419]: ---------------------------- MPD announces state update: player Dec 13 18:20:35 aura volumio[1419]: info: sendMpdCommand status took 31 milliseconds Dec 13 18:20:35 aura volumio[1419]: info: ControllerMpd::getState Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Dec 13 18:20:35 aura volumio[1419]: info: sendMpdCommand status took 31 milliseconds Dec 13 18:20:35 aura volumio[1419]: info: sendMpdCommand status took 2 milliseconds Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::parseState Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::parseState Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::parseState Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 18:20:35 aura volumio[1419]: info: sendMpdCommand status took 4 milliseconds Dec 13 18:20:35 aura volumio[1419]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 13 18:20:35 aura volumio[1419]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 13 18:20:35 aura volumio[1419]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::parseState Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::parseTrackInfo Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::parseTrackInfo Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::parseTrackInfo Dec 13 18:20:35 aura volumio[1419]: info: ControllerMpd::pushState Dec 13 18:20:35 aura volumio[1419]: info: CoreCommandRouter::servicePushState Dec 13 18:20:35 aura volumio[1419]: info: CoreStateMachine::pushState Dec 13 18:20:35 aura volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 18:20:35 aura volumio[1419]: info: CoreCommandRouter::volumioPushState Dec 13 18:20:35 aura volumio[1419]: info: ControllerMpd::pushState Dec 13 18:20:35 aura volumio[1419]: info: CoreCommandRouter::servicePushState Dec 13 18:20:35 aura volumio[1419]: info: CoreStateMachine::pushState Dec 13 18:20:35 aura volumio[1419]: info: CoreCommandRouter::volumioPushState Dec 13 18:20:35 aura volumio[1419]: info: ControllerMpd::pushState Dec 13 18:20:35 aura volumio[1419]: info: CoreCommandRouter::servicePushState Dec 13 18:20:35 aura volumio[1419]: info: CoreStateMachine::pushState Dec 13 18:20:35 aura volumio[1419]: info: CoreCommandRouter::volumioPushState Dec 13 18:20:35 aura volumio[1419]: info: ------------------------------ 54ms Dec 13 18:20:35 aura volumio[1419]: info: ------------------------------ 54ms Dec 13 18:20:35 aura volumio[1419]: info: ------------------------------ 25ms Dec 13 18:20:35 aura volumio[1419]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 13 18:20:35 aura volumio[1419]: verbose: ControllerMpd::parseTrackInfo Dec 13 18:20:35 aura volumio[1419]: info: ControllerMpd::pushState Dec 13 18:20:35 aura volumio[1419]: info: CoreCommandRouter::servicePushState Dec 13 18:20:35 aura volumio[1419]: info: CoreStateMachine::pushState Dec 13 18:20:35 aura volumio[1419]: info: CoreCommandRouter::volumioPushState Dec 13 18:20:35 aura volumio[1419]: info: ------------------------------ 36ms Dec 13 18:20:35 aura volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Dec 13 18:20:35 aura volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Dec 13 18:20:35 aura volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Dec 13 18:20:35 aura volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Dec 13 18:20:35 aura volumio[1419]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 13 18:20:35 aura volumio[1419]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 13 18:20:35 aura volumio[1419]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 13 18:20:35 aura sudo[7884]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 13 18:20:35 aura sudo[7884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:20:35 aura sudo[7886]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 13 18:20:35 aura sudo[7886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:20:35 aura volumio[1419]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 13 18:20:35 aura sudo[7890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 13 18:20:35 aura sudo[7890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:20:35 aura sudo[7892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 13 18:20:35 aura sudo[7892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 18:20:35 aura systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 13 18:20:35 aura sudo[7884]: pam_unix(sudo:session): session closed for user root Dec 13 18:20:35 aura volumio[1419]: info: peppymeterbasic Daemon Started Dec 13 18:20:35 aura sudo[7886]: pam_unix(sudo:session): session closed for user root Dec 13 18:20:35 aura volumio[1419]: info: peppymeterbasic Daemon Started Dec 13 18:20:35 aura sudo[7892]: pam_unix(sudo:session): session closed for user root Dec 13 18:20:35 aura volumio[1419]: info: peppymeterbasic Daemon Started Dec 13 18:20:35 aura sudo[7890]: pam_unix(sudo:session): session closed for user root Dec 13 18:20:35 aura volumio[1419]: info: peppymeterbasic Daemon Started Dec 13 18:20:43 aura go-librespot[1840]: time="2025-12-13T18:20:43Z" level=trace msg="sent dealer ping" Dec 13 18:20:43 aura go-librespot[1840]: time="2025-12-13T18:20:43Z" level=trace msg="received dealer pong" Dec 13 18:20:48 aura volumio[1419]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 18:20:48 aura volumio[1419]: TypeError: Cannot read properties of null (reading 'slice') Dec 13 18:20:48 aura volumio[1419]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 13 18:20:48 aura volumio[1419]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 13 18:20:48 aura volumio[1419]: at Object.onceWrapper (node:events:629:26) Dec 13 18:20:48 aura volumio[1419]: at ClientRequest.emit (node:events:514:28) Dec 13 18:20:48 aura volumio[1419]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Dec 13 18:20:48 aura volumio[1419]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Dec 13 18:20:48 aura volumio[1419]: at TLSSocket.socketOnData (node:_http_client:535:22) Dec 13 18:20:48 aura volumio[1419]: at TLSSocket.emit (node:events:514:28) Dec 13 18:20:48 aura volumio[1419]: at addChunk (node:internal/streams/readable:343:12) Dec 13 18:20:48 aura volumio[1419]: at readableAddChunk (node:internal/streams/readable:316:9) Dec 13 18:20:48 aura volumio[1419]: at Readable.push (node:internal/streams/readable:253:10) Dec 13 18:20:48 aura volumio[1419]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Dec 13 18:20:48 aura volumio[1419]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 18:20:49 aura sudo[7938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-13 18:19' Dec 13 18:20:49 aura sudo[7938]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"