-- Logs begin at Tue 2025-06-10 22:35:40 CEST, end at Tue 2025-06-10 22:38:27 CEST. -- Jun 10 22:37:02 volumiodramper systemd[1]: systemd-timedated.service: Succeeded. Jun 10 22:37:04 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 10 22:37:04 volumiodramper volumio[1176]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 10 22:37:04 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioGetState Jun 10 22:37:04 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 10 22:37:09 volumiodramper volumio-remote-updater[702]: No test mode Jun 10 22:37:09 volumiodramper volumio-remote-updater[702]: No alpha test mode Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 10 22:37:09 volumiodramper volumio[1176]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 22:37:09 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 22:37:10 volumiodramper volumio[1176]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 10 22:37:10 volumiodramper volumio[1176]: info: Completed starting MyVolumio Plugin Jun 10 22:37:10 volumiodramper volumio[1176]: [Metrics] CommandRouter: 39s 47.41ms Jun 10 22:37:10 volumiodramper volumio[1176]: info: CoreCommandRouter::volumiosetStartupVolume Jun 10 22:37:10 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 22:37:10 volumiodramper volumio[1176]: info: VolumeController:: Setting startup Volume 10 Jun 10 22:37:10 volumiodramper volumio[1176]: info: VolumeController::SetAlsaVolume10 Jun 10 22:37:10 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 22:37:10 volumiodramper volumio[1176]: info: CoreCommandRouter::Close All Modals sent Jun 10 22:37:10 volumiodramper volumio[1176]: info: CoreCommandRouter::Close All Modals sent Jun 10 22:37:10 volumiodramper volumio[1176]: info: CoreStateMachine::pushState Jun 10 22:37:10 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:10 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 22:37:10 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 10 22:37:10 volumiodramper volumio[1176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jun 10 22:37:10 volumiodramper volumio[1176]: SPOTIFY: SPOTIFY VOLUME 100 Jun 10 22:37:10 volumiodramper volumio[1176]: SPOTIFY: VOLUMIO VOLUME 10 Jun 10 22:37:10 volumiodramper volumio[1176]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 10 22:37:10 volumiodramper volumio[1176]: info: Setting Spotify Volume from Volumio: 10 Jun 10 22:37:10 volumiodramper volumio[1176]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 22:37:10 volumiodramper volumio[1176]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 10 22:37:10 volumiodramper volumio[1176]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Jun 10 22:37:10 volumiodramper volumio[1176]: aplay: main:828: audio open error: No such device Jun 10 22:37:10 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:10+02:00" level=trace msg="sent dealer ping" Jun 10 22:37:10 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:10+02:00" level=trace msg="received dealer pong" Jun 10 22:37:11 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 10 22:37:11 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 22:37:11 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 10 22:37:12 volumiodramper volumio[1176]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Jun 10 22:37:12 volumiodramper volumio[1176]: info: Sending Spotify command with payload to local API: /player/volume Jun 10 22:37:12 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:12+02:00" level=debug msg="update volume to 6553/65535" Jun 10 22:37:13 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:13+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 10 22:37:13 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:13+02:00" level=trace msg="emitting websocket event: volume" Jun 10 22:37:13 volumiodramper volumio[1176]: SPOTIFY: received: {"type":"volume","data":{"value":10,"max":100}} Jun 10 22:37:13 volumiodramper volumio[1176]: SPOTIFY: RECEIVED SPOTIFY VOLUME 10 Jun 10 22:37:17 volumiodramper ntpd[934]: Soliciting pool server 162.159.200.123 Jun 10 22:37:17 volumiodramper volumio[1176]: info: BOOT COMPLETED Jun 10 22:37:17 volumiodramper volumio[1176]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Jun 10 22:37:17 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioGetState Jun 10 22:37:17 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:17 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPause Jun 10 22:37:17 volumiodramper volumio[1176]: info: CoreStateMachine::pause Jun 10 22:37:17 volumiodramper volumio[1176]: info: mpdhttpout --- Volumio set on pause Jun 10 22:37:17 volumiodramper volumio[1176]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Jun 10 22:37:18 volumiodramper ntpd[934]: Soliciting pool server 89.161.47.131 Jun 10 22:37:19 volumiodramper ntpd[934]: Soliciting pool server 54.37.233.160 Jun 10 22:37:19 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 10 22:37:20 volumiodramper sudo[1716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 22:37:20 volumiodramper sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 22:37:20 volumiodramper systemd[1]: musicservicesshield.service: Succeeded. Jun 10 22:37:20 volumiodramper systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jun 10 22:37:20 volumiodramper systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jun 10 22:37:20 volumiodramper systemd[1]: Stopping Music Player Daemon... Jun 10 22:37:20 volumiodramper systemd[1]: mpd.service: Succeeded. Jun 10 22:37:20 volumiodramper systemd[1]: Stopped Music Player Daemon. Jun 10 22:37:20 volumiodramper ntpd[934]: Soliciting pool server 94.154.96.7 Jun 10 22:37:20 volumiodramper ntpd[934]: Soliciting pool server 193.25.222.136 Jun 10 22:37:20 volumiodramper systemd[1]: Starting Music Player Daemon... Jun 10 22:37:20 volumiodramper ntpd[934]: Soliciting pool server 193.59.134.156 Jun 10 22:37:20 volumiodramper sudo[1718]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 22:37:20 volumiodramper sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 22:37:20 volumiodramper sudo[1718]: pam_unix(sudo:session): session closed for user root Jun 10 22:37:20 volumiodramper mpd[1720]: Jun 10 22:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 22:37:20 volumiodramper systemd[1]: Started Music Player Daemon. Jun 10 22:37:20 volumiodramper sudo[1716]: pam_unix(sudo:session): session closed for user root Jun 10 22:37:20 volumiodramper volumio[1176]: error: updateQueue error: null Jun 10 22:37:20 volumiodramper systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jun 10 22:37:21 volumiodramper ntpd[934]: Soliciting pool server 213.222.217.11 Jun 10 22:37:21 volumiodramper ntpd[934]: Soliciting pool server 85.115.212.251 Jun 10 22:37:21 volumiodramper volumio[1723]: cset: --> shielding system active with Jun 10 22:37:21 volumiodramper volumio[1723]: cset: "system" cpuset of CPUSPEC(1-3) with 128 tasks running Jun 10 22:37:21 volumiodramper volumio[1723]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jun 10 22:37:21 volumiodramper systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jun 10 22:37:21 volumiodramper ntpd[934]: Soliciting pool server 193.70.94.182 Jun 10 22:37:21 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 10 22:37:21 volumiodramper volumio[1176]: info: CURURI: music-library Jun 10 22:37:21 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:37:22 volumiodramper ntpd[934]: Soliciting pool server 178.215.228.24 Jun 10 22:37:22 volumiodramper ntpd[934]: Soliciting pool server 51.68.141.5 Jun 10 22:37:22 volumiodramper ntpd[934]: Soliciting pool server 194.146.251.102 Jun 10 22:37:23 volumiodramper ntpd[934]: Soliciting pool server 2a05:f480:2400:18af:5400:4ff:fe0c:1486 Jun 10 22:37:23 volumiodramper ntpd[934]: Soliciting pool server 91.212.242.21 Jun 10 22:37:23 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 10 22:37:23 volumiodramper volumio[1176]: info: CURURI: music-library/USB Jun 10 22:37:23 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:37:24 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 10 22:37:24 volumiodramper volumio[1176]: info: CURURI: music-library/USB/INTENSO Jun 10 22:37:24 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:37:24 volumiodramper volumio[1176]: info: Preloading song: music-library/USB/INTENSO/Bruno Mars - Treasure_070554.mp3 Jun 10 22:37:24 volumiodramper volumio[1176]: info: Exploding uri music-library/USB/INTENSO/Bruno Mars - Treasure_070554.mp3 in service mpd Jun 10 22:37:24 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2FBruno%20Mars%20-%20Treasure_070554.mp3&metadata=false Jun 10 22:37:24 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/Bruno Mars - Treasure_070554.mp3 Jun 10 22:37:25 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::ClearQueue Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::stop Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 22:37:25 volumiodramper volumio[1176]: info: CorePlayQueue::clearPlayQueue Jun 10 22:37:25 volumiodramper volumio[1176]: info: CorePlayQueue::saveQueue Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::addQueueItems Jun 10 22:37:25 volumiodramper volumio[1176]: info: CorePlayQueue::addQueueItems Jun 10 22:37:25 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:37:25 volumiodramper volumio[1176]: info: Adding Item to queue: music-library/USB/INTENSO/Bruno Mars - Treasure_070554.mp3 Jun 10 22:37:25 volumiodramper volumio[1176]: info: Using cached record of: music-library/USB/INTENSO/Bruno Mars - Treasure_070554.mp3 Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jun 10 22:37:25 volumiodramper volumio[1176]: info: CorePlayQueue::saveQueue Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::updateTrackBlock Jun 10 22:37:25 volumiodramper volumio[1176]: info: CorePlayQueue::getTrackBlock Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPlay Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::play index 0 Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::addQueueItems Jun 10 22:37:25 volumiodramper volumio[1176]: info: CorePlayQueue::addQueueItems Jun 10 22:37:25 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:37:25 volumiodramper volumio[1176]: info: Adding Item to queue: music-library/USB/INTENSO/kurs-prawdopodobienstwo Jun 10 22:37:25 volumiodramper volumio[1176]: info: Exploding uri music-library/USB/INTENSO/kurs-prawdopodobienstwo in service mpd Jun 10 22:37:25 volumiodramper volumio[1176]: info: Adding Item to queue: music-library/USB/INTENSO/kurs-statystyka Jun 10 22:37:25 volumiodramper volumio[1176]: info: Exploding uri music-library/USB/INTENSO/kurs-statystyka in service mpd Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::stop Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::play index undefined Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 22:37:25 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::startPlaybackTimer Jun 10 22:37:25 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:25 volumiodramper volumio[1176]: verbose: ControllerMpd::clearAddPlayTracks USB/INTENSO/Bruno Mars - Treasure_070554.mp3 Jun 10 22:37:25 volumiodramper volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-prawdopodobienstwo%2Fbonusy%2Fwolframalpha-prawdopodobienstwo.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-prawdopodobienstwo/bonusy/wolframalpha-prawdopodobienstwo.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-prawdopodobienstwo%2Fvideo%2FLekcja%201%20Prawdopodobie%C5%A1stwo.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-prawdopodobienstwo/video/Lekcja 1 Prawdopodobiešstwo.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-prawdopodobienstwo%2Fvideo%2FLekcja%202%20Prawdopodobie%C5%A1stwo.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-prawdopodobienstwo/video/Lekcja 2 Prawdopodobiešstwo.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-prawdopodobienstwo%2Fvideo%2FLekcja%203%20Prawdopodobie%C5%A1stwo.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-prawdopodobienstwo/video/Lekcja 3 Prawdopodobiešstwo.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-prawdopodobienstwo%2Fvideo%2FLekcja%204%20Prawdopodobie%C5%A1stwo.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-prawdopodobienstwo/video/Lekcja 4 Prawdopodobiešstwo.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-prawdopodobienstwo%2Fvideo%2FLekcja%205%20Prawdopodobie%C5%A1stwo.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-prawdopodobienstwo/video/Lekcja 5 Prawdopodobiešstwo.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-prawdopodobienstwo%2Fvideo%2FLekcja%206%20Prawdopodobie%C5%A1stwo.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-prawdopodobienstwo/video/Lekcja 6 Prawdopodobiešstwo.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-prawdopodobienstwo%2Fvideo%2FLekcja%207%20Prawdopodobie%C5%A1stwo.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-prawdopodobienstwo/video/Lekcja 7 Prawdopodobiešstwo.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-statystyka%2Fvideo%2FLekcja%201%20Kurs%20Statystyka.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-statystyka/video/Lekcja 1 Kurs Statystyka.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-statystyka%2Fvideo%2FLekcja%202%20Kurs%20Statystyka.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-statystyka/video/Lekcja 2 Kurs Statystyka.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-statystyka%2Fvideo%2FLekcja%203%20Kurs%20Statystyka.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-statystyka/video/Lekcja 3 Kurs Statystyka.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-statystyka%2Fvideo%2FLekcja%204%20Kurs%20Statystyka.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-statystyka/video/Lekcja 4 Kurs Statystyka.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-statystyka%2Fvideo%2FLekcja%205%20Kurs%20Statystyka.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-statystyka/video/Lekcja 5 Kurs Statystyka.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-statystyka%2Fvideo%2FLekcja%206%20Kurs%20Statystyka.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-statystyka/video/Lekcja 6 Kurs Statystyka.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: ALBUMART /albumart?cacheid=21&path=%2Fmnt%2FUSB%2FINTENSO%2Fkurs-statystyka%2Fvideo%2FLekcja%207%20Kurs%20Statystyka.avi&metadata=false Jun 10 22:37:25 volumiodramper volumio[1176]: info: URI /mnt/USB/INTENSO/kurs-statystyka/video/Lekcja 7 Kurs Statystyka.avi Jun 10 22:37:25 volumiodramper volumio[1176]: info: sendMpdCommand stop took 13 milliseconds Jun 10 22:37:25 volumiodramper volumio[1176]: verbose: ControllerMpd::sendMpdCommand clear Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jun 10 22:37:25 volumiodramper volumio[1176]: info: CorePlayQueue::saveQueue Jun 10 22:37:25 volumiodramper volumio[1176]: info: CoreStateMachine::updateTrackBlock Jun 10 22:37:25 volumiodramper volumio[1176]: info: CorePlayQueue::getTrackBlock Jun 10 22:37:25 volumiodramper volumio[1176]: info: Jun 10 22:37:25 volumiodramper volumio[1176]: ---------------------------- MPD announces system playlist update Jun 10 22:37:25 volumiodramper volumio[1176]: info: Ignoring MPD Status Update Jun 10 22:37:25 volumiodramper volumio[1176]: info: sendMpdCommand clear took 10 milliseconds Jun 10 22:37:25 volumiodramper volumio[1176]: verbose: ControllerMpd::sendMpdCommand add "USB/INTENSO/Bruno Mars - Treasure_070554.mp3" Jun 10 22:37:25 volumiodramper volumio[1176]: error: updateQueue error: null Jun 10 22:37:25 volumiodramper volumio[1176]: info: Jun 10 22:37:25 volumiodramper volumio[1176]: ---------------------------- MPD announces system playlist update Jun 10 22:37:25 volumiodramper volumio[1176]: info: Ignoring MPD Status Update Jun 10 22:37:25 volumiodramper volumio[1176]: info: ------------------------------ 4ms Jun 10 22:37:25 volumiodramper volumio[1176]: info: sendMpdCommand add "USB/INTENSO/Bruno Mars - Treasure_070554.mp3" took 2 milliseconds Jun 10 22:37:25 volumiodramper volumio[1176]: verbose: ControllerMpd::sendMpdCommand play Jun 10 22:37:25 volumiodramper volumio[1176]: info: ------------------------------ 3ms Jun 10 22:37:25 volumiodramper volumio[1176]: info: sendMpdCommand play took 1 milliseconds Jun 10 22:37:25 volumiodramper volumio[1176]: info: Jun 10 22:37:25 volumiodramper volumio[1176]: ---------------------------- MPD announces state update: player Jun 10 22:37:25 volumiodramper volumio[1176]: info: ControllerMpd::getState Jun 10 22:37:25 volumiodramper volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:37:26 volumiodramper volumio[1176]: info: Jun 10 22:37:26 volumiodramper volumio[1176]: ---------------------------- MPD announces state update: player Jun 10 22:37:26 volumiodramper volumio[1176]: info: sendMpdCommand status took 143 milliseconds Jun 10 22:37:26 volumiodramper volumio[1176]: info: ControllerMpd::getState Jun 10 22:37:26 volumiodramper volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:37:26 volumiodramper volumio[1176]: verbose: ControllerMpd::parseState Jun 10 22:37:26 volumiodramper volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 22:37:26 volumiodramper volumio[1176]: info: sendMpdCommand status took 2 milliseconds Jun 10 22:37:26 volumiodramper volumio[1176]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 10 22:37:26 volumiodramper volumio[1176]: verbose: ControllerMpd::parseState Jun 10 22:37:26 volumiodramper volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 22:37:26 volumiodramper volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jun 10 22:37:26 volumiodramper volumio[1176]: info: ControllerMpd::pushState Jun 10 22:37:26 volumiodramper volumio[1176]: info: CoreCommandRouter::servicePushState Jun 10 22:37:26 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:26 volumiodramper volumio[1176]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Treasure","artist":"Bruno Mars","album":null,"uri":"USB/INTENSO/Bruno Mars - Treasure_070554.mp3","trackType":"mp3"} Jun 10 22:37:26 volumiodramper volumio[1176]: verbose: CURRENT POSITION 0 Jun 10 22:37:26 volumiodramper volumio[1176]: info: CoreStateMachine::syncState stateService play Jun 10 22:37:26 volumiodramper volumio[1176]: info: CoreStateMachine::syncState currentStatus stop Jun 10 22:37:26 volumiodramper volumio[1176]: info: ------------------------------ 154ms Jun 10 22:37:26 volumiodramper volumio[1176]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 10 22:37:26 volumiodramper volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jun 10 22:37:26 volumiodramper volumio[1176]: info: ControllerMpd::pushState Jun 10 22:37:26 volumiodramper volumio[1176]: info: CoreCommandRouter::servicePushState Jun 10 22:37:26 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:26 volumiodramper volumio[1176]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Treasure","artist":"Bruno Mars","album":null,"uri":"USB/INTENSO/Bruno Mars - Treasure_070554.mp3","trackType":"mp3"} Jun 10 22:37:26 volumiodramper volumio[1176]: verbose: CURRENT POSITION 0 Jun 10 22:37:26 volumiodramper volumio[1176]: info: CoreStateMachine::syncState stateService play Jun 10 22:37:26 volumiodramper volumio[1176]: info: CoreStateMachine::syncState currentStatus play Jun 10 22:37:26 volumiodramper volumio[1176]: info: Received an update from plugin. extracting info from payload Jun 10 22:37:26 volumiodramper volumio[1176]: info: CoreStateMachine::pushState Jun 10 22:37:26 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:26 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 22:37:26 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 10 22:37:26 volumiodramper volumio[1176]: info: CoreStateMachine::pushState Jun 10 22:37:26 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:26 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 10 22:37:26 volumiodramper volumio[1176]: info: ------------------------------ 23ms Jun 10 22:37:26 volumiodramper volumio[1176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jun 10 22:37:26 volumiodramper volumio[1176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jun 10 22:37:27 volumiodramper ntpd[934]: receive: Unexpected origin timestamp 0xebf31607.269c828f does not match aorg 0000000000.00000000 from server@94.154.96.7 xmt 0xebf31607.f356b454 Jun 10 22:37:27 volumiodramper ntpd[934]: receive: Unexpected origin timestamp 0xebf31607.2698e773 does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xebf31607.f568a85a Jun 10 22:37:27 volumiodramper ntpd[934]: receive: Unexpected origin timestamp 0xebf31607.26957c7e does not match aorg 0000000000.00000000 from server@194.146.251.102 xmt 0xebf31607.f7b3fa92 Jun 10 22:37:36 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jun 10 22:37:36 volumiodramper volumio[1176]: info: In handleBrowseUri, curUri=spotify Jun 10 22:37:36 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:37:36 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:37:36 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:37:36 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:37:41 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:41+02:00" level=trace msg="sent dealer ping" Jun 10 22:37:41 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:41+02:00" level=trace msg="received dealer pong" Jun 10 22:37:41 volumiodramper volumio[1176]: info: VolumeController::SetAlsaVolume26 Jun 10 22:37:42 volumiodramper volumio[1176]: info: CoreStateMachine::pushState Jun 10 22:37:42 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:42 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 22:37:42 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 10 22:37:42 volumiodramper volumio[1176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Jun 10 22:37:42 volumiodramper volumio[1176]: SPOTIFY: SPOTIFY VOLUME 10 Jun 10 22:37:42 volumiodramper volumio[1176]: SPOTIFY: VOLUMIO VOLUME 26 Jun 10 22:37:42 volumiodramper volumio[1176]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 10 22:37:42 volumiodramper volumio[1176]: info: Setting Spotify Volume from Volumio: 26 Jun 10 22:37:42 volumiodramper volumio[1176]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 22:37:43 volumiodramper volumio[1176]: SPOTIFY: SETTING SPOTIFY VOLUME 26 Jun 10 22:37:43 volumiodramper volumio[1176]: info: Sending Spotify command with payload to local API: /player/volume Jun 10 22:37:43 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:43+02:00" level=debug msg="update volume to 17039/65535" Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 22:37:44 volumiodramper volumio[1176]: info: Getting Alsa Cards List without I2S DAC Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 22:37:44 volumiodramper volumio[1176]: Invalid card number. Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 10 22:37:44 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 22:37:44 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:44+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 10 22:37:44 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:44+02:00" level=trace msg="emitting websocket event: volume" Jun 10 22:37:44 volumiodramper volumio[1176]: SPOTIFY: received: {"type":"volume","data":{"value":26,"max":100}} Jun 10 22:37:44 volumiodramper volumio[1176]: SPOTIFY: RECEIVED SPOTIFY VOLUME 26 Jun 10 22:37:53 volumiodramper volumio[1176]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioGetState Jun 10 22:37:53 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:53 volumiodramper volumio[1176]: info: Restoring Previous Volume level: 26 false true Jun 10 22:37:53 volumiodramper volumio[1176]: info: VolumeController::SetAlsaVolume100 Jun 10 22:37:53 volumiodramper volumio[1176]: info: Volume configurations have been set Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 10 22:37:53 volumiodramper volumio[1176]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 10 22:37:53 volumiodramper volumio[1176]: info: Disabling external Volume Control Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreStateMachine::pushState Jun 10 22:37:53 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 10 22:37:53 volumiodramper volumio[1176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 22:37:53 volumiodramper volumio[1176]: SPOTIFY: SPOTIFY VOLUME 26 Jun 10 22:37:53 volumiodramper volumio[1176]: SPOTIFY: VOLUMIO VOLUME 100 Jun 10 22:37:53 volumiodramper volumio[1176]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 10 22:37:53 volumiodramper volumio[1176]: info: Setting Spotify Volume from Volumio: 100 Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreStateMachine::pushState Jun 10 22:37:53 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 10 22:37:53 volumiodramper volumio[1176]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 22:37:53 volumiodramper volumio[1176]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 22:37:53 volumiodramper volumio[1176]: info: Getting Alsa Cards List without I2S DAC Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 22:37:53 volumiodramper volumio[1176]: Invalid card number. Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 22:37:53 volumiodramper volumio[1176]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioGetState Jun 10 22:37:53 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPause Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreStateMachine::pause Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreStateMachine::stPlaybackTimer Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreStateMachine::servicePause Jun 10 22:37:53 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::servicePause Jun 10 22:37:53 volumiodramper volumio[1176]: info: ControllerMpd::pause Jun 10 22:37:53 volumiodramper volumio[1176]: verbose: ControllerMpd::sendMpdCommand pause Jun 10 22:37:53 volumiodramper volumio[1176]: info: mpdhttpout --- Volumio set on pause Jun 10 22:37:53 volumiodramper volumio[1176]: info: VolumeController::SetAlsaVolume0 Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreStateMachine::pushState Jun 10 22:37:53 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 10 22:37:53 volumiodramper volumio[1176]: info: Jun 10 22:37:53 volumiodramper volumio[1176]: ---------------------------- MPD announces state update: player Jun 10 22:37:53 volumiodramper volumio[1176]: info: sendMpdCommand pause took 28 milliseconds Jun 10 22:37:53 volumiodramper volumio[1176]: info: ControllerMpd::getState Jun 10 22:37:53 volumiodramper volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jun 10 22:37:53 volumiodramper volumio[1176]: info: sendMpdCommand status took 1 milliseconds Jun 10 22:37:53 volumiodramper volumio[1176]: verbose: ControllerMpd::parseState Jun 10 22:37:53 volumiodramper volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 22:37:53 volumiodramper volumio[1176]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 22:37:53 volumiodramper volumio[1176]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 10 22:37:53 volumiodramper volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jun 10 22:37:53 volumiodramper volumio[1176]: info: ControllerMpd::pushState Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::servicePushState Jun 10 22:37:53 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:53 volumiodramper volumio[1176]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":27542,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Treasure","artist":"Bruno Mars","album":null,"uri":"USB/INTENSO/Bruno Mars - Treasure_070554.mp3","trackType":"mp3"} Jun 10 22:37:53 volumiodramper volumio[1176]: verbose: CURRENT POSITION 0 Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreStateMachine::syncState stateService pause Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreStateMachine::syncState currentStatus pause Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreStateMachine::pushState Jun 10 22:37:53 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 10 22:37:53 volumiodramper volumio[1176]: info: CoreStateMachine::stPlaybackTimer Jun 10 22:37:53 volumiodramper volumio[1176]: info: ------------------------------ 20ms Jun 10 22:37:54 volumiodramper volumio[1176]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Jun 10 22:37:54 volumiodramper volumio[1176]: info: Sending Spotify command with payload to local API: /player/volume Jun 10 22:37:54 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:54+02:00" level=debug msg="update volume to 65535/65535" Jun 10 22:37:55 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 10 22:37:55 volumiodramper sudo[1836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 22:37:55 volumiodramper sudo[1836]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 22:37:55 volumiodramper systemd[1]: musicservicesshield.service: Succeeded. Jun 10 22:37:55 volumiodramper systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jun 10 22:37:55 volumiodramper systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jun 10 22:37:55 volumiodramper systemd[1]: Stopping Music Player Daemon... Jun 10 22:37:55 volumiodramper systemd[1]: mpd.service: Succeeded. Jun 10 22:37:55 volumiodramper systemd[1]: Stopped Music Player Daemon. Jun 10 22:37:55 volumiodramper systemd[1]: Starting Music Player Daemon... Jun 10 22:37:55 volumiodramper sudo[1838]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 22:37:55 volumiodramper sudo[1838]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 22:37:55 volumiodramper sudo[1838]: pam_unix(sudo:session): session closed for user root Jun 10 22:37:55 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:55+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 10 22:37:55 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:55+02:00" level=trace msg="emitting websocket event: volume" Jun 10 22:37:55 volumiodramper volumio[1176]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jun 10 22:37:55 volumiodramper volumio[1176]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jun 10 22:37:56 volumiodramper mpd[1842]: Jun 10 22:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 22:37:56 volumiodramper systemd[1]: Started Music Player Daemon. Jun 10 22:37:56 volumiodramper sudo[1836]: pam_unix(sudo:session): session closed for user root Jun 10 22:37:56 volumiodramper volumio[1176]: error: updateQueue error: null Jun 10 22:37:56 volumiodramper systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jun 10 22:37:56 volumiodramper volumio[1845]: cset: --> shielding system active with Jun 10 22:37:56 volumiodramper volumio[1845]: cset: "system" cpuset of CPUSPEC(1-3) with 128 tasks running Jun 10 22:37:56 volumiodramper volumio[1845]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jun 10 22:37:56 volumiodramper systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jun 10 22:37:57 volumiodramper volumio[1176]: info: VolumeController::SetAlsaVolume26 Jun 10 22:37:57 volumiodramper volumio[1176]: info: CoreStateMachine::pushState Jun 10 22:37:57 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:37:57 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 22:37:57 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 10 22:37:57 volumiodramper volumio[1176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Jun 10 22:37:57 volumiodramper volumio[1176]: SPOTIFY: SPOTIFY VOLUME 100 Jun 10 22:37:57 volumiodramper volumio[1176]: SPOTIFY: VOLUMIO VOLUME 26 Jun 10 22:37:57 volumiodramper volumio[1176]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 10 22:37:57 volumiodramper volumio[1176]: info: Setting Spotify Volume from Volumio: 26 Jun 10 22:37:57 volumiodramper volumio[1176]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 22:37:59 volumiodramper volumio[1176]: SPOTIFY: SETTING SPOTIFY VOLUME 26 Jun 10 22:37:59 volumiodramper volumio[1176]: info: Sending Spotify command with payload to local API: /player/volume Jun 10 22:37:59 volumiodramper go-librespot[1428]: time="2025-06-10T22:37:59+02:00" level=debug msg="update volume to 17039/65535" Jun 10 22:38:00 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:00+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 10 22:38:00 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:00+02:00" level=trace msg="emitting websocket event: volume" Jun 10 22:38:00 volumiodramper volumio[1176]: SPOTIFY: received: {"type":"volume","data":{"value":26,"max":100}} Jun 10 22:38:00 volumiodramper volumio[1176]: SPOTIFY: RECEIVED SPOTIFY VOLUME 26 Jun 10 22:38:03 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioGetState Jun 10 22:38:03 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:38:11 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:11+02:00" level=trace msg="sent dealer ping" Jun 10 22:38:11 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:11+02:00" level=trace msg="received dealer pong" Jun 10 22:38:14 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jun 10 22:38:14 volumiodramper volumio[1176]: info: In handleBrowseUri, curUri=spotify/mytoptracks Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:3QqOcLtTU8zzlQRJCZzttP Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:6dOtVTDdiauQNBQEDOtlAB Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:0mh3kdiViRyeH8JzVL61Hw Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:3Orarc6QOlXouz459arvKF Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:5Vh4cFonbm0XdIyJIyrlPO Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:3k4gjmYNJugQRlh2aounZ9 Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:4qgZs0RNjdzKAS22lP0QjY Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:2LzEuRdNWNiHdGI1m5ALc5 Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:2D4DUc5XU6p8iBmzjPH9AZ Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:2TE4xW3ImvpltVU0cPcKUn Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:51ZQ1vr10ffzbwIjDCwqm4 Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:6ntpR3xo7Zcc9akHgcMbu5 Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:0WoFs3EdGOx58yX5BtXvOa Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:0bMSxndKeJrHs3Y3e4Sg2y Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:0AcgKF7kbSoCkh3n7vlHwK Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:1CcLA0eaauck34YEIrvAAq Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:3U74eolvC9YFA2fpNNaBWj Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:4HEOgBHRCExyYVeTyrXsnL Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:0qeCBSjI2DE44VWT4lew2G Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:60a0Rd6pjrkxjPbaKzXjfq Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:4Yf5bqU3NK4kNOypcrLYwU Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:17i5jLpzndlQhbS4SrTd0B Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:45evHLPMTNicGtSbsUvgjN Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:5AjpBUr0CyNjkMo7TbGYe8 Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:6YdWbtRFv3iHkoZyBuRoFj Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:7Im5F8fliiF16D2te5rYNv Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:1lpGsHdCpWiTvrFitLakBo Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:0xBMHcymSO7euOBnbTEHIa Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:2HRqTpkrJO5ggZyyK6NPWz Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:0W1uTK6I97CbjFKAVtRGfK Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:34MoKBRdC9JDjcL4b4X1Ic Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:4uUG5RXrOk84mYEfFvj3cK Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:7HklgS1hnxYF6uwCB0zpOA Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:1JekRMGQ8iN4G1AAdh0SBa Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:48lxT5qJF0yYyf2z4wB4xW Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:4UzVcXufOhGUwF56HT7b8M Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:2nLtzopw4rPReszdYBJU6h Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:0bYg9bo50gSsH3LtXe2SQn Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:1qDrWA6lyx8cLECdZE7TV7 Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:5p6xhgQCwzX0G9PadMU9GA Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:6rCz0E0G6El6irdft7Zjgp Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:6zAiRKvAMlXHxEtyO4yxIO Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:0w2EeNJGGlbUSx1DfkqV5L Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:2HBBM75Xv3o2Mqdyh1NcM0 Jun 10 22:38:15 volumiodramper volumio[1176]: info: Preloading song: spotify:track:384lHduuDsrR4HPUwR3fG4 Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:3QqOcLtTU8zzlQRJCZzttP in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:3QqOcLtTU8zzlQRJCZzttP Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:6dOtVTDdiauQNBQEDOtlAB in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:6dOtVTDdiauQNBQEDOtlAB Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3QqOcLtTU8zzlQRJCZzttP","service":"spop","name":"C’est La Vie (with bbno$ & Rich Brian)","artist":"Yung Gravy","album":"Marvelous","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b2734684ae99ac25a39d60e0a23b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:0mh3kdiViRyeH8JzVL61Hw in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:0mh3kdiViRyeH8JzVL61Hw Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:3Orarc6QOlXouz459arvKF in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:3Orarc6QOlXouz459arvKF Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6dOtVTDdiauQNBQEDOtlAB","service":"spop","name":"BIRDS OF A FEATHER","artist":"Billie Eilish","album":"HIT ME HARD AND SOFT","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b27371d62ea7ea8a5be92d3c1f62","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0mh3kdiViRyeH8JzVL61Hw","service":"spop","name":"Believe","artist":"Elton John","album":"Made In England","type":"song","duration":295,"albumart":"https://i.scdn.co/image/ab67616d0000b273f727aa61e7330d1e1b636e29","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:5Vh4cFonbm0XdIyJIyrlPO in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:5Vh4cFonbm0XdIyJIyrlPO Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Orarc6QOlXouz459arvKF","service":"spop","name":"Żyd","artist":"Łydka Grubasa","album":"ĘĆ","type":"song","duration":250,"albumart":"https://i.scdn.co/image/ab67616d0000b2738de280f2226b39718153b83a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:3k4gjmYNJugQRlh2aounZ9 in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:3k4gjmYNJugQRlh2aounZ9 Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5Vh4cFonbm0XdIyJIyrlPO","service":"spop","name":"Explosion","artist":"Kalwi & Remi","album":"Always in Trance","type":"song","duration":248,"albumart":"https://i.scdn.co/image/ab67616d0000b2733fe0073d8750f8d3edf94c72","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:4qgZs0RNjdzKAS22lP0QjY in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:4qgZs0RNjdzKAS22lP0QjY Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:2LzEuRdNWNiHdGI1m5ALc5 in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:2LzEuRdNWNiHdGI1m5ALc5 Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3k4gjmYNJugQRlh2aounZ9","service":"spop","name":"Over Each Other","artist":"Linkin Park","album":"From Zero","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b273b11a5489e8cb11dd22b930a0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:2D4DUc5XU6p8iBmzjPH9AZ in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:2D4DUc5XU6p8iBmzjPH9AZ Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4qgZs0RNjdzKAS22lP0QjY","service":"spop","name":"Where The Streets Have No Name - Remastered","artist":"U2","album":"The Joshua Tree (Super Deluxe)","type":"song","duration":337,"albumart":"https://i.scdn.co/image/ab67616d0000b273b7bea3d01f04e6d0408d2afe","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:2TE4xW3ImvpltVU0cPcKUn in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:2TE4xW3ImvpltVU0cPcKUn Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2LzEuRdNWNiHdGI1m5ALc5","service":"spop","name":"Traffic - Radio Edit","artist":"Tiësto","album":"Just Be","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b2730bcce148c9c4fd4ad709fdcc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2D4DUc5XU6p8iBmzjPH9AZ","service":"spop","name":"HERO","artist":"Kizo","album":"PATOPOP (Vol. 1)","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b27337bdb557e66ad593fcb47216","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:51ZQ1vr10ffzbwIjDCwqm4 in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:51ZQ1vr10ffzbwIjDCwqm4 Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2TE4xW3ImvpltVU0cPcKUn","service":"spop","name":"Merry Christmas Everyone","artist":"Shakin' Stevens","album":"Merry Christmas Everyone","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b273d962f3b4235f8c6429a829fb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:6ntpR3xo7Zcc9akHgcMbu5 in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:6ntpR3xo7Zcc9akHgcMbu5 Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:0WoFs3EdGOx58yX5BtXvOa in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:0WoFs3EdGOx58yX5BtXvOa Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:51ZQ1vr10ffzbwIjDCwqm4","service":"spop","name":"we can't be friends (wait for your love)","artist":"Ariana Grande","album":"eternal sunshine","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b2738b58d20f1b77295730db15b4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:0bMSxndKeJrHs3Y3e4Sg2y in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:0bMSxndKeJrHs3Y3e4Sg2y Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0WoFs3EdGOx58yX5BtXvOa","service":"spop","name":"Your Love","artist":"The Outfield","album":"Play Deep","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b273cd7e670a91d0263d2ee1d8a7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6ntpR3xo7Zcc9akHgcMbu5","service":"spop","name":"Scandal - Remastered 2011","artist":"Queen","album":"The Miracle (2011 Remaster)","type":"song","duration":282,"albumart":"https://i.scdn.co/image/ab67616d0000b27327fda1adf296f242416b4230","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:0AcgKF7kbSoCkh3n7vlHwK in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:0AcgKF7kbSoCkh3n7vlHwK Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0bMSxndKeJrHs3Y3e4Sg2y","service":"spop","name":"Hymn (J. Słowacki)","artist":"sanah","album":"sanah śpiewa Poezyje","type":"song","duration":252,"albumart":"https://i.scdn.co/image/ab67616d0000b273af7bf5de59e3fbd9ea0e3dae","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:15 volumiodramper volumio[1176]: info: Exploding uri spotify:track:1CcLA0eaauck34YEIrvAAq in service spop Jun 10 22:38:15 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:1CcLA0eaauck34YEIrvAAq Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:3U74eolvC9YFA2fpNNaBWj in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:3U74eolvC9YFA2fpNNaBWj Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0AcgKF7kbSoCkh3n7vlHwK","service":"spop","name":"Dirty Diana - 2012 Remaster","artist":"Michael Jackson","album":"Bad (Remastered)","type":"song","duration":280,"albumart":"https://i.scdn.co/image/ab67616d0000b27362e97ae5072de10850578af5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:4HEOgBHRCExyYVeTyrXsnL in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:4HEOgBHRCExyYVeTyrXsnL Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1CcLA0eaauck34YEIrvAAq","service":"spop","name":"When We Were Young (The Logical Song)","artist":"David Guetta","album":"When We Were Young (The Logical Song)","type":"song","duration":147,"albumart":"https://i.scdn.co/image/ab67616d0000b273c8e153557024fd571a5a83d6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:0qeCBSjI2DE44VWT4lew2G in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:0qeCBSjI2DE44VWT4lew2G Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3U74eolvC9YFA2fpNNaBWj","service":"spop","name":"I'm In","artist":"a-ha","album":"True North","type":"song","duration":305,"albumart":"https://i.scdn.co/image/ab67616d0000b273bcca3b0b137aca922fe61d8d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:60a0Rd6pjrkxjPbaKzXjfq in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:60a0Rd6pjrkxjPbaKzXjfq Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4HEOgBHRCExyYVeTyrXsnL","service":"spop","name":"Jingle Bells - Remastered 1999","artist":"Frank Sinatra","album":"A Jolly Christmas From Frank Sinatra","type":"song","duration":120,"albumart":"https://i.scdn.co/image/ab67616d0000b27394fd2e614d0862bdd2592594","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:4Yf5bqU3NK4kNOypcrLYwU in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:4Yf5bqU3NK4kNOypcrLYwU Jun 10 22:38:16 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::ClearQueue Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::stop Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::updateTrackBlock Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::getTrackBlock Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::stPlaybackTimer Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::pushState Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::serviceStop Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 0 Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreCommandRouter::serviceStop Jun 10 22:38:16 volumiodramper volumio[1176]: info: ControllerMpd::stop Jun 10 22:38:16 volumiodramper volumio[1176]: verbose: ControllerMpd::sendMpdCommand stop Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::clearPlayQueue Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::saveQueue Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::addQueueItems Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::addQueueItems Jun 10 22:38:16 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:3QqOcLtTU8zzlQRJCZzttP Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:3QqOcLtTU8zzlQRJCZzttP Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:6dOtVTDdiauQNBQEDOtlAB Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:6dOtVTDdiauQNBQEDOtlAB Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:0mh3kdiViRyeH8JzVL61Hw Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:0mh3kdiViRyeH8JzVL61Hw Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:3Orarc6QOlXouz459arvKF Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:3Orarc6QOlXouz459arvKF Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:5Vh4cFonbm0XdIyJIyrlPO Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:5Vh4cFonbm0XdIyJIyrlPO Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:3k4gjmYNJugQRlh2aounZ9 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:3k4gjmYNJugQRlh2aounZ9 Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::saveQueue Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::updateTrackBlock Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::getTrackBlock Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPlay Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::play index 5 Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::addQueueItems Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::addQueueItems Jun 10 22:38:16 volumiodramper volumio[1176]: info: Preload queue cleared Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:4qgZs0RNjdzKAS22lP0QjY Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:4qgZs0RNjdzKAS22lP0QjY Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:2LzEuRdNWNiHdGI1m5ALc5 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:2LzEuRdNWNiHdGI1m5ALc5 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:2D4DUc5XU6p8iBmzjPH9AZ Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:2D4DUc5XU6p8iBmzjPH9AZ Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:2TE4xW3ImvpltVU0cPcKUn Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:2TE4xW3ImvpltVU0cPcKUn Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:51ZQ1vr10ffzbwIjDCwqm4 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:51ZQ1vr10ffzbwIjDCwqm4 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:6ntpR3xo7Zcc9akHgcMbu5 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:6ntpR3xo7Zcc9akHgcMbu5 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:0WoFs3EdGOx58yX5BtXvOa Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:0WoFs3EdGOx58yX5BtXvOa Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:0bMSxndKeJrHs3Y3e4Sg2y Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:0bMSxndKeJrHs3Y3e4Sg2y Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:0AcgKF7kbSoCkh3n7vlHwK Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:0AcgKF7kbSoCkh3n7vlHwK Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:1CcLA0eaauck34YEIrvAAq Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:1CcLA0eaauck34YEIrvAAq Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:3U74eolvC9YFA2fpNNaBWj Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:3U74eolvC9YFA2fpNNaBWj Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:4HEOgBHRCExyYVeTyrXsnL Jun 10 22:38:16 volumiodramper volumio[1176]: info: Using cached record of: spotify:track:4HEOgBHRCExyYVeTyrXsnL Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:0qeCBSjI2DE44VWT4lew2G Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:0qeCBSjI2DE44VWT4lew2G in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:0qeCBSjI2DE44VWT4lew2G Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:60a0Rd6pjrkxjPbaKzXjfq Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:60a0Rd6pjrkxjPbaKzXjfq in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:60a0Rd6pjrkxjPbaKzXjfq Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:4Yf5bqU3NK4kNOypcrLYwU Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:4Yf5bqU3NK4kNOypcrLYwU in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:4Yf5bqU3NK4kNOypcrLYwU Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:17i5jLpzndlQhbS4SrTd0B Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:17i5jLpzndlQhbS4SrTd0B in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:17i5jLpzndlQhbS4SrTd0B Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:45evHLPMTNicGtSbsUvgjN Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:45evHLPMTNicGtSbsUvgjN in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:45evHLPMTNicGtSbsUvgjN Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:5AjpBUr0CyNjkMo7TbGYe8 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:5AjpBUr0CyNjkMo7TbGYe8 in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:5AjpBUr0CyNjkMo7TbGYe8 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:6YdWbtRFv3iHkoZyBuRoFj Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:6YdWbtRFv3iHkoZyBuRoFj in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:6YdWbtRFv3iHkoZyBuRoFj Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:7Im5F8fliiF16D2te5rYNv Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:7Im5F8fliiF16D2te5rYNv in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:7Im5F8fliiF16D2te5rYNv Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:1lpGsHdCpWiTvrFitLakBo Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:1lpGsHdCpWiTvrFitLakBo in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:1lpGsHdCpWiTvrFitLakBo Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:0xBMHcymSO7euOBnbTEHIa Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:0xBMHcymSO7euOBnbTEHIa in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:0xBMHcymSO7euOBnbTEHIa Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:2HRqTpkrJO5ggZyyK6NPWz Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:2HRqTpkrJO5ggZyyK6NPWz in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:2HRqTpkrJO5ggZyyK6NPWz Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:0W1uTK6I97CbjFKAVtRGfK Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:0W1uTK6I97CbjFKAVtRGfK in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:0W1uTK6I97CbjFKAVtRGfK Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:34MoKBRdC9JDjcL4b4X1Ic Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:34MoKBRdC9JDjcL4b4X1Ic in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:34MoKBRdC9JDjcL4b4X1Ic Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:4uUG5RXrOk84mYEfFvj3cK Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:4uUG5RXrOk84mYEfFvj3cK in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:4uUG5RXrOk84mYEfFvj3cK Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:7HklgS1hnxYF6uwCB0zpOA Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:7HklgS1hnxYF6uwCB0zpOA in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:7HklgS1hnxYF6uwCB0zpOA Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:1JekRMGQ8iN4G1AAdh0SBa Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:1JekRMGQ8iN4G1AAdh0SBa in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:1JekRMGQ8iN4G1AAdh0SBa Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:48lxT5qJF0yYyf2z4wB4xW Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:48lxT5qJF0yYyf2z4wB4xW in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:48lxT5qJF0yYyf2z4wB4xW Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:4UzVcXufOhGUwF56HT7b8M Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:4UzVcXufOhGUwF56HT7b8M in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:4UzVcXufOhGUwF56HT7b8M Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:2nLtzopw4rPReszdYBJU6h Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:2nLtzopw4rPReszdYBJU6h in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:2nLtzopw4rPReszdYBJU6h Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:0bYg9bo50gSsH3LtXe2SQn Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:0bYg9bo50gSsH3LtXe2SQn in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:0bYg9bo50gSsH3LtXe2SQn Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:1qDrWA6lyx8cLECdZE7TV7 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:1qDrWA6lyx8cLECdZE7TV7 in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:1qDrWA6lyx8cLECdZE7TV7 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:5p6xhgQCwzX0G9PadMU9GA Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:5p6xhgQCwzX0G9PadMU9GA in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:5p6xhgQCwzX0G9PadMU9GA Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:6rCz0E0G6El6irdft7Zjgp Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:6rCz0E0G6El6irdft7Zjgp in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:6rCz0E0G6El6irdft7Zjgp Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:6zAiRKvAMlXHxEtyO4yxIO Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:6zAiRKvAMlXHxEtyO4yxIO in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:6zAiRKvAMlXHxEtyO4yxIO Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:0w2EeNJGGlbUSx1DfkqV5L Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:0w2EeNJGGlbUSx1DfkqV5L in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:0w2EeNJGGlbUSx1DfkqV5L Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:2HBBM75Xv3o2Mqdyh1NcM0 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:2HBBM75Xv3o2Mqdyh1NcM0 in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:2HBBM75Xv3o2Mqdyh1NcM0 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Adding Item to queue: spotify:track:384lHduuDsrR4HPUwR3fG4 Jun 10 22:38:16 volumiodramper volumio[1176]: info: Exploding uri spotify:track:384lHduuDsrR4HPUwR3fG4 in service spop Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: EXPLODING URI:spotify:track:384lHduuDsrR4HPUwR3fG4 Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::stop Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::play index undefined Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 5 Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::startPlaybackTimer Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 5 Jun 10 22:38:16 volumiodramper volumio[1176]: info: [1749587896306] ControllerSpotify::clearAddPlayTrack Jun 10 22:38:16 volumiodramper volumio[1176]: info: Sending Spotify command with payload to local API: /player/play Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Jun 10 22:38:16 volumiodramper volumio[1176]: info: sendMpdCommand stop took 119 milliseconds Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:60a0Rd6pjrkxjPbaKzXjfq","service":"spop","name":"In the End","artist":"Linkin Park","album":"Hybrid Theory (Bonus Edition)","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b273e2f039481babe23658fc719a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0qeCBSjI2DE44VWT4lew2G","service":"spop","name":"Miss Sarajevo - 2005 Live From Milan","artist":"U2","album":"U218 Singles (Deluxe Version)","type":"song","duration":421,"albumart":"https://i.scdn.co/image/ab67616d0000b2731aa69db93ccf406b7fdaa911","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=debug msg="resolved context of track" uri="spotify:track:3k4gjmYNJugQRlh2aounZ9" Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:3k4gjmYNJugQRlh2aounZ9" Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3k4gjmYNJugQRlh2aounZ9" Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=trace msg="emitting websocket event: will_play" Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=debug msg="selected format OGG_VORBIS_320 (536a61b57a28293746dd8153735ca5f1b954f9dd)" uri="spotify:track:3k4gjmYNJugQRlh2aounZ9" Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=debug msg="requested aes key for file 536a61b57a28293746dd8153735ca5f1b954f9dd, gid: 3k4gjmYNJugQRlh2aounZ9" Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1369" Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3k4gjmYNJugQRlh2aounZ9","play_origin":"go-librespot"}} Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1lpGsHdCpWiTvrFitLakBo","service":"spop","name":"I Could Be The One (Avicii Vs. Nicky Romero) - Radio Edit","artist":"Avicii","album":"Avicii Forever","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b273d586098ba36b54d4167de337","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Yf5bqU3NK4kNOypcrLYwU","service":"spop","name":"Faint","artist":"Linkin Park","album":"Meteora","type":"song","duration":162,"albumart":"https://i.scdn.co/image/ab67616d0000b2735f1f51d14e8bea89484ecd1b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6zAiRKvAMlXHxEtyO4yxIO","service":"spop","name":"Phoenix","artist":"League of Legends","album":"Phoenix","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273a91543c0ec0301cca07792dc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0xBMHcymSO7euOBnbTEHIa","service":"spop","name":"I'm Good (Blue) (Nightcore)","artist":"Syrex","album":"I'm Good (Blue) (Nightcore)","type":"song","duration":130,"albumart":"https://i.scdn.co/image/ab67616d0000b273fc47ff430b7984cfe47b7621","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5p6xhgQCwzX0G9PadMU9GA","service":"spop","name":"Play The Game - Remastered 2011","artist":"Queen","album":"The Game (2011 Remaster)","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b273056e90910cbaf5c5b892aeba","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:384lHduuDsrR4HPUwR3fG4","service":"spop","name":"Nic dwa razy (W. Szymborska)","artist":"sanah","album":"sanah śpiewa Poezyje","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b273af7bf5de59e3fbd9ea0e3dae","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0bYg9bo50gSsH3LtXe2SQn","service":"spop","name":"All I Want for Christmas Is You","artist":"Mariah Carey","album":"Merry Christmas","type":"song","duration":241,"albumart":"https://i.scdn.co/image/ab67616d0000b2734246e3158421f5abb75abc4f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0qeCBSjI2DE44VWT4lew2G","service":"spop","name":"Miss Sarajevo - 2005 Live From Milan","artist":"U2","album":"U218 Singles (Deluxe Version)","type":"song","duration":421,"albumart":"https://i.scdn.co/image/ab67616d0000b2731aa69db93ccf406b7fdaa911","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7HklgS1hnxYF6uwCB0zpOA","service":"spop","name":"Is This Love (feat. Michael Shynes)","artist":"Komodo","album":"Is This Love (feat. Michael Shynes)","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b273bbe2831e08780bd3d387315c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4uUG5RXrOk84mYEfFvj3cK","service":"spop","name":"I'm Good (Blue)","artist":"David Guetta","album":"I'm Good (Blue)","type":"song","duration":175,"albumart":"https://i.scdn.co/image/ab67616d0000b273933c036cd61cd40d3f17a9c4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0w2EeNJGGlbUSx1DfkqV5L","service":"spop","name":"Desert Rose - Radio Edit","artist":"Sting","album":"Brand New Day (Expanded Edition)","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b273ca578f53323863e37db8221a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:60a0Rd6pjrkxjPbaKzXjfq","service":"spop","name":"In the End","artist":"Linkin Park","album":"Hybrid Theory (Bonus Edition)","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b273e2f039481babe23658fc719a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5AjpBUr0CyNjkMo7TbGYe8","service":"spop","name":"Stay With Me","artist":"Snupe Dimon","album":"Incase Your Wondering","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b273226b201a18806bc18a3c13e6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:17i5jLpzndlQhbS4SrTd0B","service":"spop","name":"Cello Suite No. 1 in G Major, BWV 1007: I. Prélude","artist":"Johann Sebastian Bach","album":"Bach: Cello Suites Nos. 1, 5 & 6","type":"song","duration":152,"albumart":"https://i.scdn.co/image/ab67616d0000b273cbb64bd416bb225456100bbc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2HBBM75Xv3o2Mqdyh1NcM0","service":"spop","name":"Heavy Is the Crown","artist":"Linkin Park","album":"From Zero","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b273b11a5489e8cb11dd22b930a0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:45evHLPMTNicGtSbsUvgjN","service":"spop","name":"Superhero - Junior Eurovision 2019 / Poland","artist":"Viki Gabor","album":"Getaway (Into My Imagination)","type":"song","duration":174,"albumart":"https://i.scdn.co/image/ab67616d0000b273b4518c9ae313d7900358393c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6rCz0E0G6El6irdft7Zjgp","service":"spop","name":"Falochrony","artist":"Roxie","album":"Falochrony","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b273e9de07f1a49bf3ec3696c2d1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7Im5F8fliiF16D2te5rYNv","service":"spop","name":"Hammer To Fall - Remastered 2011","artist":"Queen","album":"The Works (2011 Remaster)","type":"song","duration":265,"albumart":"https://i.scdn.co/image/ab67616d0000b2735be5f807f6f0549e198a44b4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1qDrWA6lyx8cLECdZE7TV7","service":"spop","name":"Somebody That I Used To Know","artist":"Gotye","album":"Making Mirrors","type":"song","duration":244,"albumart":"https://i.scdn.co/image/ab67616d0000b2738ac5768205ad97df3f4f4c0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0W1uTK6I97CbjFKAVtRGfK","service":"spop","name":"Too Much Love Will Kill You - Remastered 2011","artist":"Queen","album":"Made In Heaven (2011 Remaster)","type":"song","duration":259,"albumart":"https://i.scdn.co/image/ab67616d0000b2735f8c271d01157fdb59478f14","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:34MoKBRdC9JDjcL4b4X1Ic","service":"spop","name":"It's A Hard Life - Remastered 2011","artist":"Queen","album":"The Works (2011 Remaster)","type":"song","duration":246,"albumart":"https://i.scdn.co/image/ab67616d0000b2735be5f807f6f0549e198a44b4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Yf5bqU3NK4kNOypcrLYwU","service":"spop","name":"Faint","artist":"Linkin Park","album":"Meteora","type":"song","duration":162,"albumart":"https://i.scdn.co/image/ab67616d0000b2735f1f51d14e8bea89484ecd1b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2HRqTpkrJO5ggZyyK6NPWz","service":"spop","name":"Espresso","artist":"Sabrina Carpenter","album":"Short n' Sweet","type":"song","duration":175,"albumart":"https://i.scdn.co/image/ab67616d0000b273fd8d7a8d96871e791cb1f626","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6YdWbtRFv3iHkoZyBuRoFj","service":"spop","name":"Hava Nagila","artist":"Hamnawa","album":"Hava Nagila","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b273b2e646d0daff33fad244f2c5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:48lxT5qJF0yYyf2z4wB4xW","service":"spop","name":"Pedro","artist":"Jaxomy","album":"Pedro","type":"song","duration":144,"albumart":"https://i.scdn.co/image/ab67616d0000b273df8680015ddf76e5c444849f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1JekRMGQ8iN4G1AAdh0SBa","service":"spop","name":"Enjoy the Silence","artist":"Depeche Mode","album":"Violator (Deluxe)","type":"song","duration":372,"albumart":"https://i.scdn.co/image/ab67616d0000b273234bbbad4dce31cd2950af3e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4UzVcXufOhGUwF56HT7b8M","service":"spop","name":"My Immortal","artist":"Evanescence","album":"Fallen","type":"song","duration":262,"albumart":"https://i.scdn.co/image/ab67616d0000b27325f49ab23f0ec6332efef432","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2nLtzopw4rPReszdYBJU6h","service":"spop","name":"Numb","artist":"Linkin Park","album":"Meteora","type":"song","duration":187,"albumart":"https://i.scdn.co/image/ab67616d0000b2735f1f51d14e8bea89484ecd1b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioPushQueue Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::saveQueue Jun 10 22:38:16 volumiodramper volumio[1176]: info: CoreStateMachine::updateTrackBlock Jun 10 22:38:16 volumiodramper volumio[1176]: info: CorePlayQueue::getTrackBlock Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 10 22:38:16 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:16+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1133" Jun 10 22:38:17 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:17+02:00" level=debug msg="fetched first chunk of 13, total size is 6553595 bytes" uri="spotify:track:3k4gjmYNJugQRlh2aounZ9" Jun 10 22:38:17 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:17+02:00" level=debug msg="created new output device" Jun 10 22:38:17 volumiodramper go-librespot[1428]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Jun 10 22:38:17 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:17+02:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed setting stream for spotify:track:3k4gjmYNJugQRlh2aounZ9: ALSA error at snd_pcm_open: No such device" Jun 10 22:38:17 volumiodramper volumio[1176]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Jun 10 22:38:17 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:17+02:00" level=debug msg="fetched chunk 1/12, size: 524288" uri="spotify:track:3k4gjmYNJugQRlh2aounZ9" Jun 10 22:38:17 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:17+02:00" level=debug msg="fetched chunk 2/12, size: 524288" uri="spotify:track:3k4gjmYNJugQRlh2aounZ9" Jun 10 22:38:17 volumiodramper go-librespot[1428]: time="2025-06-10T22:38:17+02:00" level=debug msg="fetched chunk 3/12, size: 524288" uri="spotify:track:3k4gjmYNJugQRlh2aounZ9" Jun 10 22:38:23 volumiodramper volumio[1176]: info: CoreCommandRouter::volumioGetState Jun 10 22:38:23 volumiodramper volumio[1176]: info: CorePlayQueue::getTrack 5 Jun 10 22:38:26 volumiodramper volumio[1176]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jun 10 22:38:26 volumiodramper volumio[1176]: info: PLUGIN onReboot : networkfs Jun 10 22:38:26 volumiodramper volumio[1176]: info: PLUGIN onReboot : audiophonicsonoff Jun 10 22:38:26 volumiodramper volumio[1176]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 22:38:26 volumiodramper volumio[1176]: TypeError: Cannot read property 'writeSync' of undefined Jun 10 22:38:26 volumiodramper volumio[1176]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Jun 10 22:38:26 volumiodramper volumio[1176]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jun 10 22:38:26 volumiodramper volumio[1176]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jun 10 22:38:26 volumiodramper volumio[1176]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 10 22:38:26 volumiodramper volumio[1176]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 10 22:38:26 volumiodramper volumio[1176]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jun 10 22:38:26 volumiodramper volumio[1176]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Jun 10 22:38:26 volumiodramper volumio[1176]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Jun 10 22:38:26 volumiodramper volumio[1176]: at Socket.emit (events.js:315:20) Jun 10 22:38:26 volumiodramper volumio[1176]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 10 22:38:26 volumiodramper volumio[1176]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 10 22:38:26 volumiodramper volumio[1176]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 22:38:27 volumiodramper sudo[1957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 22:37 Jun 10 22:38:27 volumiodramper sudo[1957]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"