-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sun 2024-10-20 17:30:15 CEST. -- Oct 20 17:29:05 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:05+02:00" level=debug msg="prefetching next track" uri="spotify:track:5jXwU3GF0NkC7upZcozmSN" Oct 20 17:29:06 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:06+02:00" level=debug msg="selected format OGG_VORBIS_320 (eb0d6d363048a9a6710dd6ddffbc421b107f7b4a)" uri="spotify:track:5jXwU3GF0NkC7upZcozmSN" Oct 20 17:29:06 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:06+02:00" level=debug msg="requested aes key for file eb0d6d363048a9a6710dd6ddffbc421b107f7b4a, gid: 5jXwU3GF0NkC7upZcozmSN" Oct 20 17:29:06 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:06+02:00" level=debug msg="fetched first chunk of 26, total size is 13126232 bytes" uri="spotify:track:5jXwU3GF0NkC7upZcozmSN" Oct 20 17:29:06 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:06+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:5jXwU3GF0NkC7upZcozmSN" Oct 20 17:29:06 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:06+02:00" level=info msg="prefetched track \"Number The Brave\" (duration: 299480ms)" uri="spotify:track:5jXwU3GF0NkC7upZcozmSN" Oct 20 17:29:07 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:07+02:00" level=debug msg="fetched chunk 1/25, size: 524288" uri="spotify:track:5jXwU3GF0NkC7upZcozmSN" Oct 20 17:29:07 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:07+02:00" level=debug msg="fetched chunk 3/25, size: 524288" uri="spotify:track:5jXwU3GF0NkC7upZcozmSN" Oct 20 17:29:07 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:07+02:00" level=debug msg="fetched chunk 2/25, size: 524288" uri="spotify:track:5jXwU3GF0NkC7upZcozmSN" Oct 20 17:29:22 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 20 17:29:22 volumio-mixer volumio[605]: info: Received Get System Info Oct 20 17:29:22 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 17:29:22 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 17:29:22 volumio-mixer volumio[605]: info: Discovery: Getting this device information Oct 20 17:29:22 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioGetState Oct 20 17:29:22 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 17:29:33 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:33+02:00" level=debug msg="handling pause player command from 31ceb10fae1c01b44babb8230d6dca8b4bff2452" Oct 20 17:29:33 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:33+02:00" level=debug msg="pause track at 272802ms" Oct 20 17:29:33 volumio-mixer volumiologrotate[400]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 20 17:29:33 volumio-mixer volumiologrotate[400]: ls: cannot access 'MIXER': No such file or directory Oct 20 17:29:33 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 20 17:29:33 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:33+02:00" level=debug msg="sending successful reply for dealer request" Oct 20 17:29:33 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 20 17:29:33 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:33+02:00" level=trace msg="emitting websocket event: paused" Oct 20 17:29:33 volumio-mixer volumio[605]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3xfjNINXi5Z6PUdv50JTwq","play_origin":"playlist"}} Oct 20 17:29:33 volumio-mixer volumio[605]: SPOTIFY: PUSH STATE SPOTIFY Oct 20 17:29:33 volumio-mixer volumio[605]: SPOTIFY: {"status":"pause","service":"spop","title":"Domenica Lunatica - Live","artist":"Vasco Rossi","album":"VASCO NONSTOP LIVE (Live)","albumart":"https://i.scdn.co/image/ab67616d00001e02a253ab7aa0718c6f05f3627e","uri":"spotify:track:3xfjNINXi5Z6PUdv50JTwq","trackType":"spotify","seek":272001,"duration":275,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 20 17:29:33 volumio-mixer volumio[605]: info: CoreCommandRouter::servicePushState Oct 20 17:29:33 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:33 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 17:29:33 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:33 volumio-mixer volumio[605]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3xfjNINXi5Z6PUdv50JTwq","play_origin":"playlist"}} Oct 20 17:29:33 volumio-mixer volumio[605]: SPOTIFY: PUSH STATE SPOTIFY Oct 20 17:29:33 volumio-mixer volumio[605]: SPOTIFY: {"status":"pause","service":"spop","title":"Domenica Lunatica - Live","artist":"Vasco Rossi","album":"VASCO NONSTOP LIVE (Live)","albumart":"https://i.scdn.co/image/ab67616d00001e02a253ab7aa0718c6f05f3627e","uri":"spotify:track:3xfjNINXi5Z6PUdv50JTwq","trackType":"spotify","seek":272001,"duration":275,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 20 17:29:33 volumio-mixer volumio[605]: info: CoreCommandRouter::servicePushState Oct 20 17:29:33 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:33 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:33 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:34 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:34 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:34 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:34 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:34 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:34 volumio-mixer volumio[605]: info: touch_display: Setting screensaver timeout to 60 seconds. Oct 20 17:29:37 volumio-mixer volumio[605]: info: Preload queue cleared Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreStateMachine::ClearQueue Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreStateMachine::stop Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreStateMachine::serviceStop Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreCommandRouter::serviceStop Oct 20 17:29:37 volumio-mixer volumio[605]: info: Spotify Stop Oct 20 17:29:37 volumio-mixer volumio[605]: SPOTIFY: SPOTIFY STOP Oct 20 17:29:37 volumio-mixer volumio[605]: SPOTIFY: {"status":"pause","title":"Domenica Lunatica - Live","artist":"Vasco Rossi","album":"VASCO NONSTOP LIVE (Live)","albumart":"https://i.scdn.co/image/ab67616d00001e02a253ab7aa0718c6f05f3627e","uri":"spotify:track:3xfjNINXi5Z6PUdv50JTwq","trackType":"spotify","codec":"ogg","seek":272001,"duration":275,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":true,"volatile":true,"service":"spop"} Oct 20 17:29:37 volumio-mixer volumio[605]: info: Sending Spotify command to local API: /player/pause Oct 20 17:29:37 volumio-mixer volumio[605]: info: CorePlayQueue::clearPlayQueue Oct 20 17:29:37 volumio-mixer volumio[605]: info: CorePlayQueue::saveQueue Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushQueue Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreStateMachine::addQueueItems Oct 20 17:29:37 volumio-mixer volumio[605]: info: CorePlayQueue::addQueueItems Oct 20 17:29:37 volumio-mixer volumio[605]: info: Preload queue cleared Oct 20 17:29:37 volumio-mixer volumio[605]: info: Adding Item to queue: spotify:user:spotify:playlist:6OKVtve5YAlp9xibBENUoa Oct 20 17:29:37 volumio-mixer volumio[605]: info: Exploding uri spotify:user:spotify:playlist:6OKVtve5YAlp9xibBENUoa in service spop Oct 20 17:29:37 volumio-mixer volumio[605]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:6OKVtve5YAlp9xibBENUoa Oct 20 17:29:37 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:37+02:00" level=debug msg="pause track at 273374ms" Oct 20 17:29:37 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:37+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 20 17:29:37 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:37+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 20 17:29:37 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:37+02:00" level=trace msg="emitting websocket event: paused" Oct 20 17:29:37 volumio-mixer volumio[605]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3xfjNINXi5Z6PUdv50JTwq","play_origin":"playlist"}} Oct 20 17:29:37 volumio-mixer volumio[605]: SPOTIFY: PUSH STATE SPOTIFY Oct 20 17:29:37 volumio-mixer volumio[605]: SPOTIFY: {"status":"pause","service":"spop","title":"Domenica Lunatica - Live","artist":"Vasco Rossi","album":"VASCO NONSTOP LIVE (Live)","albumart":"https://i.scdn.co/image/ab67616d00001e02a253ab7aa0718c6f05f3627e","uri":"spotify:track:3xfjNINXi5Z6PUdv50JTwq","trackType":"spotify","seek":272001,"duration":275,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreCommandRouter::servicePushState Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:37 volumio-mixer volumio[605]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3xfjNINXi5Z6PUdv50JTwq","play_origin":"playlist"}} Oct 20 17:29:37 volumio-mixer volumio[605]: SPOTIFY: PUSH STATE SPOTIFY Oct 20 17:29:37 volumio-mixer volumio[605]: SPOTIFY: {"status":"pause","service":"spop","title":"Domenica Lunatica - Live","artist":"Vasco Rossi","album":"VASCO NONSTOP LIVE (Live)","albumart":"https://i.scdn.co/image/ab67616d00001e02a253ab7aa0718c6f05f3627e","uri":"spotify:track:3xfjNINXi5Z6PUdv50JTwq","trackType":"spotify","seek":272001,"duration":275,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreCommandRouter::servicePushState Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:37 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:38 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:38 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:38 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:38 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:38 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:38 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushQueue Oct 20 17:29:38 volumio-mixer volumio[605]: info: CorePlayQueue::saveQueue Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreStateMachine::updateTrackBlock Oct 20 17:29:38 volumio-mixer volumio[605]: info: CorePlayQueue::getTrackBlock Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPlay Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreStateMachine::play index 0 Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreStateMachine::stop Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreStateMachine::updateTrackBlock Oct 20 17:29:38 volumio-mixer volumio[605]: info: CorePlayQueue::getTrackBlock Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreStateMachine::stPlaybackTimer Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:38 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreStateMachine::serviceStop Oct 20 17:29:38 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreCommandRouter::serviceStop Oct 20 17:29:38 volumio-mixer volumio[605]: info: Spotify Stop Oct 20 17:29:38 volumio-mixer volumio[605]: SPOTIFY: SPOTIFY STOP Oct 20 17:29:38 volumio-mixer volumio[605]: SPOTIFY: {"status":"pause","title":"Domenica Lunatica - Live","artist":"Vasco Rossi","album":"VASCO NONSTOP LIVE (Live)","albumart":"https://i.scdn.co/image/ab67616d00001e02a253ab7aa0718c6f05f3627e","uri":"spotify:track:3xfjNINXi5Z6PUdv50JTwq","trackType":"spotify","codec":"ogg","seek":272001,"duration":275,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":true,"volatile":true,"service":"spop"} Oct 20 17:29:38 volumio-mixer volumio[605]: info: Sending Spotify command to local API: /player/pause Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreStateMachine::play index undefined Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 17:29:38 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:38 volumio-mixer volumio[605]: info: CoreStateMachine::startPlaybackTimer Oct 20 17:29:38 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:38 volumio-mixer volumio[605]: info: [1729438178777] ControllerSpotify::clearAddPlayTrack Oct 20 17:29:38 volumio-mixer volumio[605]: info: Sending Spotify command with payload to local API: /player/play Oct 20 17:29:38 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:38+02:00" level=debug msg="pause track at 273374ms" Oct 20 17:29:38 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:38 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:39 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:39 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 20 17:29:39 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:39+02:00" level=debug msg="resolved context of track" uri="spotify:track:0jLeyuu6mtsD384054xLah" Oct 20 17:29:39 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:39+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0jLeyuu6mtsD384054xLah" Oct 20 17:29:39 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:39+02:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:0jLeyuu6mtsD384054xLah" Oct 20 17:29:39 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 20 17:29:39 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:39+02:00" level=trace msg="emitting websocket event: will_play" Oct 20 17:29:39 volumio-mixer volumio[605]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0jLeyuu6mtsD384054xLah","play_origin":"go-librespot"}} Oct 20 17:29:39 volumio-mixer volumio[605]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0jLeyuu6mtsD384054xLah","play_origin":"go-librespot"}} Oct 20 17:29:39 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:39+02:00" level=debug msg="selected format OGG_VORBIS_320 (fff3caa09d6c4f07119a65b6bf498b4ab9145e69)" uri="spotify:track:0jLeyuu6mtsD384054xLah" Oct 20 17:29:39 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:39+02:00" level=debug msg="requested aes key for file fff3caa09d6c4f07119a65b6bf498b4ab9145e69, gid: 0jLeyuu6mtsD384054xLah" Oct 20 17:29:39 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:39+02:00" level=debug msg="fetched first chunk of 15, total size is 7576024 bytes" uri="spotify:track:0jLeyuu6mtsD384054xLah" Oct 20 17:29:39 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:39+02:00" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" uri="spotify:track:0jLeyuu6mtsD384054xLah" Oct 20 17:29:39 volumio-mixer kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 20 17:29:39 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:39+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Oct 20 17:29:39 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:39+02:00" level=info msg="loaded track \"Ciao Ciao\" (paused: false, position: 1ms, duration: 184311ms, prefetched: false)" uri="spotify:track:0jLeyuu6mtsD384054xLah" Oct 20 17:29:40 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:40+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 20 17:29:40 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:40+02:00" level=trace msg="scheduling prefetch in 154s" Oct 20 17:29:40 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:40+02:00" level=trace msg="emitting websocket event: metadata" Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0jLeyuu6mtsD384054xLah","name":"Ciao Ciao","artist_names":["La rappresentante di lista"],"album_name":"Ciao Ciao","album_cover_url":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","position":1,"duration":184311,"release_date":"year:2022 month:2 day:2","track_number":1,"disc_number":1}} Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0jLeyuu6mtsD384054xLah","name":"Ciao Ciao","artist_names":["La rappresentante di lista"],"album_name":"Ciao Ciao","album_cover_url":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","position":1,"duration":184311,"release_date":"year:2022 month:2 day:2","track_number":1,"disc_number":1}} Oct 20 17:29:40 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:40+02:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:0jLeyuu6mtsD384054xLah" Oct 20 17:29:40 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:40+02:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:0jLeyuu6mtsD384054xLah" Oct 20 17:29:40 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:40+02:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:0jLeyuu6mtsD384054xLah" Oct 20 17:29:40 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:40+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 20 17:29:40 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:40+02:00" level=trace msg="emitting websocket event: paused" Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0jLeyuu6mtsD384054xLah","play_origin":"go-librespot"}} Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: PUSH STATE SPOTIFY Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: {"status":"pause","service":"spop","title":"Ciao Ciao","artist":"La rappresentante di lista","album":"Ciao Ciao","albumart":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","uri":"spotify:track:0jLeyuu6mtsD384054xLah","trackType":"spotify","seek":1,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreCommandRouter::servicePushState Oct 20 17:29:40 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:40 volumio-mixer volumio[605]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Ciao Ciao","artist":"La rappresentante di lista","album":"Ciao Ciao","albumart":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","uri":"spotify:track:0jLeyuu6mtsD384054xLah","trackType":"spotify","seek":1,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 20 17:29:40 volumio-mixer volumio[605]: verbose: CURRENT POSITION 0 Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreStateMachine::syncState stateService pause Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreStateMachine::syncState currentStatus stop Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:40 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0jLeyuu6mtsD384054xLah","play_origin":"go-librespot"}} Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: PUSH STATE SPOTIFY Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: {"status":"pause","service":"spop","title":"Ciao Ciao","artist":"La rappresentante di lista","album":"Ciao Ciao","albumart":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","uri":"spotify:track:0jLeyuu6mtsD384054xLah","trackType":"spotify","seek":1,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreCommandRouter::servicePushState Oct 20 17:29:40 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:40 volumio-mixer volumio[605]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Ciao Ciao","artist":"La rappresentante di lista","album":"Ciao Ciao","albumart":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","uri":"spotify:track:0jLeyuu6mtsD384054xLah","trackType":"spotify","seek":1,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 20 17:29:40 volumio-mixer volumio[605]: verbose: CURRENT POSITION 0 Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreStateMachine::syncState stateService pause Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreStateMachine::syncState currentStatus stop Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:40 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:40 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:40+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 20 17:29:40 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:40+02:00" level=trace msg="emitting websocket event: playing" Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0jLeyuu6mtsD384054xLah","play_origin":"go-librespot"}} Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: PUSH STATE SPOTIFY Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: {"status":"play","service":"spop","title":"Ciao Ciao","artist":"La rappresentante di lista","album":"Ciao Ciao","albumart":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","uri":"spotify:track:0jLeyuu6mtsD384054xLah","trackType":"spotify","seek":1,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreCommandRouter::servicePushState Oct 20 17:29:40 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:40 volumio-mixer volumio[605]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ciao Ciao","artist":"La rappresentante di lista","album":"Ciao Ciao","albumart":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","uri":"spotify:track:0jLeyuu6mtsD384054xLah","trackType":"spotify","seek":1,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 20 17:29:40 volumio-mixer volumio[605]: verbose: CURRENT POSITION 0 Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreStateMachine::syncState stateService play Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreStateMachine::syncState currentStatus stop Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0jLeyuu6mtsD384054xLah","play_origin":"go-librespot"}} Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: PUSH STATE SPOTIFY Oct 20 17:29:40 volumio-mixer volumio[605]: SPOTIFY: {"status":"play","service":"spop","title":"Ciao Ciao","artist":"La rappresentante di lista","album":"Ciao Ciao","albumart":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","uri":"spotify:track:0jLeyuu6mtsD384054xLah","trackType":"spotify","seek":1,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreCommandRouter::servicePushState Oct 20 17:29:40 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:40 volumio-mixer volumio[605]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ciao Ciao","artist":"La rappresentante di lista","album":"Ciao Ciao","albumart":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","uri":"spotify:track:0jLeyuu6mtsD384054xLah","trackType":"spotify","seek":1,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 20 17:29:40 volumio-mixer volumio[605]: verbose: CURRENT POSITION 0 Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreStateMachine::syncState stateService play Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreStateMachine::syncState currentStatus play Oct 20 17:29:40 volumio-mixer volumio[605]: info: Received an update from plugin. extracting info from payload Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:40 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:40 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:40 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: PUSH STATE SPOTIFY Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: {"status":"play","service":"spop","title":"Ciao Ciao","artist":"La rappresentante di lista","album":"Ciao Ciao","albumart":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","uri":"spotify:track:0jLeyuu6mtsD384054xLah","trackType":"spotify","seek":1,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreCommandRouter::servicePushState Oct 20 17:29:41 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:41 volumio-mixer volumio[605]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ciao Ciao","artist":"La rappresentante di lista","album":"Ciao Ciao","albumart":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","uri":"spotify:track:0jLeyuu6mtsD384054xLah","trackType":"spotify","seek":1,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 20 17:29:41 volumio-mixer volumio[605]: verbose: CURRENT POSITION 0 Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreStateMachine::syncState stateService play Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreStateMachine::syncState currentStatus play Oct 20 17:29:41 volumio-mixer volumio[605]: info: Received an update from plugin. extracting info from payload Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:41 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:41 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: PUSH STATE SPOTIFY Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: {"status":"play","service":"spop","title":"Ciao Ciao","artist":"La rappresentante di lista","album":"Ciao Ciao","albumart":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","uri":"spotify:track:0jLeyuu6mtsD384054xLah","trackType":"spotify","seek":1,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreCommandRouter::servicePushState Oct 20 17:29:41 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:41 volumio-mixer volumio[605]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ciao Ciao","artist":"La rappresentante di lista","album":"Ciao Ciao","albumart":"https://i.scdn.co/image/ab67616d00001e020f1c471dae67e1626c9fd385","uri":"spotify:track:0jLeyuu6mtsD384054xLah","trackType":"spotify","seek":1,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 20 17:29:41 volumio-mixer volumio[605]: verbose: CURRENT POSITION 0 Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreStateMachine::syncState stateService play Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreStateMachine::syncState currentStatus play Oct 20 17:29:41 volumio-mixer volumio[605]: info: Received an update from plugin. extracting info from payload Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:41 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreStateMachine::pushState Oct 20 17:29:41 volumio-mixer volumio[605]: info: CorePlayQueue::getTrack 0 Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreCommandRouter::volumioPushState Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 20 17:29:41 volumio-mixer volumio[605]: info: CURURI: music-library Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 17:29:41 volumio-mixer volumio[605]: info: Preload queue cleared Oct 20 17:29:43 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 20 17:29:43 volumio-mixer volumio[605]: info: CURURI: music-library/NAS Oct 20 17:29:43 volumio-mixer volumio[605]: info: Preload queue cleared Oct 20 17:29:44 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 20 17:29:44 volumio-mixer volumio[605]: info: CURURI: music-library/NAS/Multimedia Oct 20 17:29:46 volumio-mixer volumio[605]: info: Preload queue cleared Oct 20 17:29:48 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 20 17:29:48 volumio-mixer volumio[605]: info: CURURI: music-library/NAS/Multimedia/music Oct 20 17:29:54 volumio-mixer volumio[605]: info: Preload queue cleared Oct 20 17:29:54 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 20 17:29:54 volumio-mixer volumio[605]: info: CURURI: music-library/NAS/Multimedia/music/musica Oct 20 17:29:54 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 20 17:29:54 volumio-mixer volumio[605]: info: CURURI: music-library/NAS/Multimedia/music/musica Oct 20 17:29:54 volumio-mixer go-librespot[5136]: time="2024-10-20T17:29:54+02:00" level=debug msg="fetched chunk 4/14, size: 524288" uri="spotify:track:0jLeyuu6mtsD384054xLah" Oct 20 17:29:54 volumio-mixer volumio[605]: info: Preload queue cleared Oct 20 17:29:54 volumio-mixer volumio[605]: info: Preload queue cleared Oct 20 17:29:58 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 20 17:29:58 volumio-mixer volumio[605]: info: CURURI: music-library/NAS/Multimedia/music/musica/U2 Oct 20 17:30:00 volumio-mixer volumio[605]: info: Preload queue cleared Oct 20 17:30:02 volumio-mixer volumio[605]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 20 17:30:02 volumio-mixer volumio[605]: info: CURURI: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC] Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preload queue cleared Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/09 - Pride (In the Name of Love).flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/05 - All Along the Watchtower.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/10 - Angel of Harlem.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/04 - Hawkmoon 269.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/11 - Love Rescue Me.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/14 - God Part II.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/13 - Heartland.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/12 - When Love Comes to Town.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/01 - Helter Skelter.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/16 - Bullet the Blue Sky.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/02 - Van Diemen's Land.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/17 - All I Want Is You.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/06 - I Still Haven't Found What I'm Looking For.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/03 - Desire.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/08 - Silver and Gold.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/15 - The Star Spangled Banner.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/07 - Freedom for My People.flac Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/Rattle and Hum.CUE Oct 20 17:30:03 volumio-mixer volumio[605]: info: Preloading song: music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/U2 - Rattle and Hum.m3u Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/09 - Pride (In the Name of Love).flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/05 - All Along the Watchtower.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/10 - Angel of Harlem.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/04 - Hawkmoon 269.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/11 - Love Rescue Me.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/14 - God Part II.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/13 - Heartland.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/12 - When Love Comes to Town.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/01 - Helter Skelter.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/16 - Bullet the Blue Sky.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/02 - Van Diemen's Land.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/17 - All I Want Is You.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/06 - I Still Haven't Found What I'm Looking For.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/03 - Desire.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/08 - Silver and Gold.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/15 - The Star Spangled Banner.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/07 - Freedom for My People.flac in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/Rattle and Hum.CUE in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: Exploding uri music-library/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/U2 - Rattle and Hum.m3u in service mpd Oct 20 17:30:06 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F09%20-%20Pride%20(In%20the%20Name%20of%20Love).flac&metadata=false Oct 20 17:30:06 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/09 - Pride (In the Name of Love).flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F05%20-%20All%20Along%20the%20Watchtower.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/05 - All Along the Watchtower.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F10%20-%20Angel%20of%20Harlem.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/10 - Angel of Harlem.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F04%20-%20Hawkmoon%20269.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/04 - Hawkmoon 269.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F11%20-%20Love%20Rescue%20Me.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/11 - Love Rescue Me.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F14%20-%20God%20Part%20II.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/14 - God Part II.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F13%20-%20Heartland.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/13 - Heartland.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F12%20-%20When%20Love%20Comes%20to%20Town.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/12 - When Love Comes to Town.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F01%20-%20Helter%20Skelter.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/01 - Helter Skelter.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F16%20-%20Bullet%20the%20Blue%20Sky.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/16 - Bullet the Blue Sky.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F02%20-%20Van%20Diemen's%20Land.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/02 - Van Diemen's Land.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F17%20-%20All%20I%20Want%20Is%20You.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/17 - All I Want Is You.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F06%20-%20I%20Still%20Haven't%20Found%20What%20I'm%20Looking%20For.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/06 - I Still Haven't Found What I'm Looking For.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F03%20-%20Desire.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/03 - Desire.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F08%20-%20Silver%20and%20Gold.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/08 - Silver and Gold.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F15%20-%20The%20Star%20Spangled%20Banner.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/15 - The Star Spangled Banner.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2F07%20-%20Freedom%20for%20My%20People.flac&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/07 - Freedom for My People.flac Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2FRattle%20and%20Hum.CUE&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/Rattle and Hum.CUE Oct 20 17:30:07 volumio-mixer volumio[605]: info: ALBUMART /albumart?cacheid=225&web=U2/Rattle%20and%20Hum/extralarge&path=%2Fmnt%2FNAS%2FMultimedia%2Fmusic%2Fmusica%2FU2%2FU2%20-%20Rattle%20And%20Hum%20(1988)%20%5BFLAC%5D%2FRattle%20and%20Hum.CUE&metadata=false Oct 20 17:30:07 volumio-mixer volumio[605]: info: URI /mnt/NAS/Multimedia/music/musica/U2/U2 - Rattle And Hum (1988) [FLAC]/Rattle and Hum.CUE Oct 20 17:30:07 volumio-mixer volumio[605]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 17:30:07 volumio-mixer go-librespot[5136]: time="2024-10-20T17:30:07+02:00" level=debug msg="fetched chunk 5/14, size: 524288" uri="spotify:track:0jLeyuu6mtsD384054xLah" Oct 20 17:30:10 volumio-mixer volumio[605]: Error: Unable to resolve or reject the same promise twice Oct 20 17:30:10 volumio-mixer volumio[605]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 20 17:30:10 volumio-mixer volumio[605]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Oct 20 17:30:10 volumio-mixer volumio[605]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 20 17:30:10 volumio-mixer volumio[605]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 20 17:30:10 volumio-mixer volumio[605]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 20 17:30:10 volumio-mixer volumio[605]: at Socket.emit (events.js:315:20) Oct 20 17:30:10 volumio-mixer volumio[605]: at addChunk (internal/streams/readable.js:309:12) Oct 20 17:30:10 volumio-mixer volumio[605]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 20 17:30:10 volumio-mixer volumio[605]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 20 17:30:10 volumio-mixer volumio[605]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 20 17:30:10 volumio-mixer volumio[605]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 17:30:10 volumio-mixer go-librespot[5136]: time="2024-10-20T17:30:10+02:00" level=debug msg="handling pause player command from 31ceb10fae1c01b44babb8230d6dca8b4bff2452" Oct 20 17:30:10 volumio-mixer go-librespot[5136]: time="2024-10-20T17:30:10+02:00" level=debug msg="pause track at 30659ms" Oct 20 17:30:10 volumio-mixer go-librespot[5136]: time="2024-10-20T17:30:10+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 20 17:30:10 volumio-mixer go-librespot[5136]: time="2024-10-20T17:30:10+02:00" level=debug msg="sending successful reply for dealer request" Oct 20 17:30:11 volumio-mixer go-librespot[5136]: time="2024-10-20T17:30:11+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 20 17:30:11 volumio-mixer go-librespot[5136]: time="2024-10-20T17:30:11+02:00" level=trace msg="emitting websocket event: paused" Oct 20 17:30:15 volumio-mixer sudo[6107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-20 17:29 Oct 20 17:30:15 volumio-mixer sudo[6107]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"