-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2024-11-20 18:52:50 CET. -- Nov 20 18:52:25 volumio systemd[1]: Starting Cleanup of Temporary Directories... Nov 20 18:52:25 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Nov 20 18:52:25 volumio systemd[1]: Started Cleanup of Temporary Directories. Nov 20 18:52:36 volumio kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Nominal byte clock 72000000; scale by 4/12 Nov 20 18:52:38 volumio volumio[1115]: info: CoreCommandRouter::volumioPlay Nov 20 18:52:38 volumio volumio[1115]: info: CoreStateMachine::play index undefined Nov 20 18:52:38 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:52:38 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:38 volumio volumio[1115]: info: CoreStateMachine::startPlaybackTimer Nov 20 18:52:38 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:38 volumio volumio[1115]: info: Spotify Resume Nov 20 18:52:38 volumio volumio[1115]: info: Sending Spotify command to local API: /player/resume Nov 20 18:52:38 volumio go-librespot[1336]: time="2024-11-20T18:52:38+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Nov 20 18:52:38 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 20 18:52:38 volumio go-librespot[1336]: time="2024-11-20T18:52:38+01:00" level=debug msg="resume track at 0ms" Nov 20 18:52:38 volumio go-librespot[1336]: time="2024-11-20T18:52:38+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:38 volumio go-librespot[1336]: time="2024-11-20T18:52:38+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:38 volumio go-librespot[1336]: time="2024-11-20T18:52:38+01:00" level=trace msg="emitting websocket event: playing" Nov 20 18:52:38 volumio volumio[1115]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4juKOTfrg6CvNBAdgZAhDH","play_origin":"go-librespot"}} Nov 20 18:52:38 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:38 volumio volumio[1115]: SPOTIFY: {"status":"play","service":"spop","title":"Punkpanda Peter","artist":"Randale","album":"Punkpanda Peter","albumart":"https://i.scdn.co/image/ab67616d00001e0229045ddd8ad02a2ab346d90a","uri":"spotify:track:4juKOTfrg6CvNBAdgZAhDH","trackType":"spotify","seek":0,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:38 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:38 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:38 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Punkpanda Peter","artist":"Randale","album":"Punkpanda Peter","albumart":"https://i.scdn.co/image/ab67616d00001e0229045ddd8ad02a2ab346d90a","uri":"spotify:track:4juKOTfrg6CvNBAdgZAhDH","trackType":"spotify","seek":0,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:38 volumio volumio[1115]: verbose: CURRENT POSITION 1 Nov 20 18:52:38 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Nov 20 18:52:38 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus pause Nov 20 18:52:38 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:38 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:38 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:38 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:38 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:38 volumio volumio[1115]: info: CorePlayQueue::getTrack 5 Nov 20 18:52:38 volumio volumio[1115]: info: Prefetching next song Nov 20 18:52:38 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:38 volumio volumio[1115]: SPOTIFY: {"status":"play","service":"spop","title":"Punkpanda Peter","artist":"Randale","album":"Punkpanda Peter","albumart":"https://i.scdn.co/image/ab67616d00001e0229045ddd8ad02a2ab346d90a","uri":"spotify:track:4juKOTfrg6CvNBAdgZAhDH","trackType":"spotify","seek":0,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:38 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:38 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:38 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Punkpanda Peter","artist":"Randale","album":"Punkpanda Peter","albumart":"https://i.scdn.co/image/ab67616d00001e0229045ddd8ad02a2ab346d90a","uri":"spotify:track:4juKOTfrg6CvNBAdgZAhDH","trackType":"spotify","seek":0,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:38 volumio volumio[1115]: verbose: CURRENT POSITION 1 Nov 20 18:52:38 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Nov 20 18:52:38 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus play Nov 20 18:52:38 volumio volumio[1115]: info: Received an update from plugin. extracting info from payload Nov 20 18:52:38 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:38 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:38 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:38 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:38 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:38 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:38 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:39 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:39 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:39 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:39 volumio volumio[1115]: info: Spotify seek to: 0 Nov 20 18:52:39 volumio volumio[1115]: info: Sending Spotify command with payload to local API: /player/seek Nov 20 18:52:39 volumio go-librespot[1336]: time="2024-11-20T18:52:39+01:00" level=debug msg="seek track to 0ms" Nov 20 18:52:39 volumio go-librespot[1336]: time="2024-11-20T18:52:39+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Nov 20 18:52:39 volumio go-librespot[1336]: time="2024-11-20T18:52:39+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:39 volumio go-librespot[1336]: time="2024-11-20T18:52:39+01:00" level=trace msg="emitting websocket event: seek" Nov 20 18:52:39 volumio volumio[1115]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:4juKOTfrg6CvNBAdgZAhDH","position":0,"duration":217373,"play_origin":"go-librespot"}} Nov 20 18:52:39 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:39 volumio volumio[1115]: SPOTIFY: {"status":"play","service":"spop","title":"Punkpanda Peter","artist":"Randale","album":"Punkpanda Peter","albumart":"https://i.scdn.co/image/ab67616d00001e0229045ddd8ad02a2ab346d90a","uri":"spotify:track:4juKOTfrg6CvNBAdgZAhDH","trackType":"spotify","seek":0,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:39 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:39 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:39 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Punkpanda Peter","artist":"Randale","album":"Punkpanda Peter","albumart":"https://i.scdn.co/image/ab67616d00001e0229045ddd8ad02a2ab346d90a","uri":"spotify:track:4juKOTfrg6CvNBAdgZAhDH","trackType":"spotify","seek":0,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:39 volumio volumio[1115]: verbose: CURRENT POSITION 1 Nov 20 18:52:39 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Nov 20 18:52:39 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus play Nov 20 18:52:39 volumio volumio[1115]: info: Received an update from plugin. extracting info from payload Nov 20 18:52:39 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:39 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:39 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:39 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:39 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:39 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:39 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:40 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::stop Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::stPlaybackTimer Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Nov 20 18:52:40 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:40 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::serviceStop Nov 20 18:52:40 volumio volumio[1115]: info: CorePlayQueue::getTrack 1 Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::serviceStop Nov 20 18:52:40 volumio volumio[1115]: info: Spotify Stop Nov 20 18:52:40 volumio volumio[1115]: SPOTIFY: SPOTIFY STOP Nov 20 18:52:40 volumio volumio[1115]: SPOTIFY: {"status":"play","position":1,"title":"Punkpanda Peter","artist":"Randale","album":"Punkpanda Peter","albumart":"https://i.scdn.co/image/ab67616d0000b27329045ddd8ad02a2ab346d90a","uri":"spotify:track:4juKOTfrg6CvNBAdgZAhDH","trackType":"spotify","codec":"ogg","seek":251,"duration":217,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Nov 20 18:52:40 volumio volumio[1115]: info: Sending Spotify command to local API: /player/pause Nov 20 18:52:40 volumio volumio[1115]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 20 18:52:40 volumio go-librespot[1336]: time="2024-11-20T18:52:40+01:00" level=debug msg="pause track at 556ms" Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Nov 20 18:52:40 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::serviceClearAddPlay Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::serviceClearAddPlayTracks Nov 20 18:52:40 volumio volumio[1115]: error: WARNING: No clearAddPlayTracks method for service spop Nov 20 18:52:40 volumio go-librespot[1336]: time="2024-11-20T18:52:40+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:40 volumio go-librespot[1336]: time="2024-11-20T18:52:40+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:40 volumio go-librespot[1336]: time="2024-11-20T18:52:40+01:00" level=trace msg="emitting websocket event: paused" Nov 20 18:52:40 volumio volumio[1115]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4juKOTfrg6CvNBAdgZAhDH","play_origin":"go-librespot"}} Nov 20 18:52:40 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:40 volumio volumio[1115]: SPOTIFY: {"status":"pause","service":"spop","title":"Punkpanda Peter","artist":"Randale","album":"Punkpanda Peter","albumart":"https://i.scdn.co/image/ab67616d00001e0229045ddd8ad02a2ab346d90a","uri":"spotify:track:4juKOTfrg6CvNBAdgZAhDH","trackType":"spotify","seek":0,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:40 volumio volumio[1115]: info: CorePlayQueue::getTrack 4 Nov 20 18:52:40 volumio volumio[1115]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Punkpanda Peter","artist":"Randale","album":"Punkpanda Peter","albumart":"https://i.scdn.co/image/ab67616d00001e0229045ddd8ad02a2ab346d90a","uri":"spotify:track:4juKOTfrg6CvNBAdgZAhDH","trackType":"spotify","seek":0,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:40 volumio volumio[1115]: verbose: CURRENT POSITION 4 Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::syncState stateService pause Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:40 volumio volumio[1115]: info: CorePlayQueue::getTrack 4 Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Nov 20 18:52:40 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::serviceClearAddPlay Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::serviceClearAddPlayTracks Nov 20 18:52:40 volumio volumio[1115]: error: WARNING: No clearAddPlayTracks method for service spop Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::play index undefined Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:52:40 volumio volumio[1115]: info: CorePlayQueue::getTrack 8 Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::startPlaybackTimer Nov 20 18:52:40 volumio volumio[1115]: info: CorePlayQueue::getTrack 8 Nov 20 18:52:40 volumio volumio[1115]: info: [1732125160594] ControllerSpotify::clearAddPlayTrack Nov 20 18:52:40 volumio volumio[1115]: info: Sending Spotify command with payload to local API: /player/play Nov 20 18:52:40 volumio go-librespot[1336]: time="2024-11-20T18:52:40+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Nov 20 18:52:40 volumio go-librespot[1336]: time="2024-11-20T18:52:40+01:00" level=debug msg="loading track spotify:track:6fyEl50wOCu6RI4AWaq7Og (paused: false, position: 0ms)" Nov 20 18:52:40 volumio go-librespot[1336]: time="2024-11-20T18:52:40+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:40 volumio go-librespot[1336]: time="2024-11-20T18:52:40+01:00" level=trace msg="emitting websocket event: will_play" Nov 20 18:52:40 volumio volumio[1115]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6fyEl50wOCu6RI4AWaq7Og","play_origin":"go-librespot"}} Nov 20 18:52:40 volumio go-librespot[1336]: time="2024-11-20T18:52:40+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:6fyEl50wOCu6RI4AWaq7Og" Nov 20 18:52:40 volumio go-librespot[1336]: time="2024-11-20T18:52:40+01:00" level=debug msg="requested aes key for file 728f9b63ee6c99f45a6d51a4b61c3128bc42e027, gid: 6fyEl50wOCu6RI4AWaq7Og" Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Nov 20 18:52:40 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Nov 20 18:52:40 volumio volumio[1115]: info: CoreStateMachine::serviceClearAddPlay Nov 20 18:52:40 volumio volumio[1115]: info: CoreCommandRouter::serviceClearAddPlayTracks Nov 20 18:52:40 volumio volumio[1115]: error: WARNING: No clearAddPlayTracks method for service spop Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=debug msg="fetched first chunk of 15, total size is 7654048 bytes" Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Nov 20 18:52:41 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=info msg="loaded track \"Biberlied (Zähneputzen)\" (uri: spotify:track:6fyEl50wOCu6RI4AWaq7Og, paused: false, position: 0ms, duration: 178906ms)" Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=trace msg="emitting websocket event: metadata" Nov 20 18:52:41 volumio volumio[1115]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6fyEl50wOCu6RI4AWaq7Og","name":"Biberlied (Zähneputzen)","artist_names":["Randale"],"album_name":"Tierparklieder aus Olderdissen","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0228627202d41cb2661577b3df","position":0,"duration":178906,"release_date":"year:2004","track_number":4,"disc_number":1}} Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=debug msg="fetched chunk 1/14, size: 524288" Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=trace msg="emitting websocket event: playing" Nov 20 18:52:41 volumio volumio[1115]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6fyEl50wOCu6RI4AWaq7Og","play_origin":"go-librespot"}} Nov 20 18:52:41 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:41 volumio volumio[1115]: SPOTIFY: {"status":"play","service":"spop","title":"Biberlied (Zähneputzen)","artist":"Randale","album":"Tierparklieder aus Olderdissen","albumart":"https://i.scdn.co/image/ab67616d00001e0228627202d41cb2661577b3df","uri":"spotify:track:6fyEl50wOCu6RI4AWaq7Og","trackType":"spotify","seek":0,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:41 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Nov 20 18:52:41 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Biberlied (Zähneputzen)","artist":"Randale","album":"Tierparklieder aus Olderdissen","albumart":"https://i.scdn.co/image/ab67616d00001e0228627202d41cb2661577b3df","uri":"spotify:track:6fyEl50wOCu6RI4AWaq7Og","trackType":"spotify","seek":0,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:41 volumio volumio[1115]: verbose: CURRENT POSITION 0 Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=debug msg="fetched chunk 2/14, size: 524288" Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=debug msg="fetched chunk 3/14, size: 524288" Nov 20 18:52:41 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:41 volumio volumio[1115]: SPOTIFY: {"status":"play","service":"spop","title":"Biberlied (Zähneputzen)","artist":"Randale","album":"Tierparklieder aus Olderdissen","albumart":"https://i.scdn.co/image/ab67616d00001e0228627202d41cb2661577b3df","uri":"spotify:track:6fyEl50wOCu6RI4AWaq7Og","trackType":"spotify","seek":0,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:41 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Nov 20 18:52:41 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Biberlied (Zähneputzen)","artist":"Randale","album":"Tierparklieder aus Olderdissen","albumart":"https://i.scdn.co/image/ab67616d00001e0228627202d41cb2661577b3df","uri":"spotify:track:6fyEl50wOCu6RI4AWaq7Og","trackType":"spotify","seek":0,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:41 volumio volumio[1115]: verbose: CURRENT POSITION 0 Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus play Nov 20 18:52:41 volumio volumio[1115]: info: Received an update from plugin. extracting info from payload Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:41 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:41 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:41 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::stop Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::stPlaybackTimer Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Nov 20 18:52:41 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:41 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::serviceStop Nov 20 18:52:41 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::serviceStop Nov 20 18:52:41 volumio volumio[1115]: info: Spotify Stop Nov 20 18:52:41 volumio volumio[1115]: SPOTIFY: SPOTIFY STOP Nov 20 18:52:41 volumio volumio[1115]: SPOTIFY: {"status":"play","position":0,"title":"Geburtstagslied","artist":"Randale","album":"Hasentotenkopfpiraten","albumart":"https://i.scdn.co/image/ab67616d0000b2732ea0f7413290fe64b26b39e3","uri":"spotify:track:4s6By8HjGUxstLQR09dwho","trackType":"spotify","codec":"ogg","seek":0,"duration":178,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Nov 20 18:52:41 volumio volumio[1115]: info: Sending Spotify command to local API: /player/pause Nov 20 18:52:41 volumio volumio[1115]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=debug msg="pause track at 911ms" Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Nov 20 18:52:41 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::serviceClearAddPlay Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::serviceClearAddPlayTracks Nov 20 18:52:41 volumio volumio[1115]: error: WARNING: No clearAddPlayTracks method for service spop Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:41 volumio go-librespot[1336]: time="2024-11-20T18:52:41+01:00" level=trace msg="emitting websocket event: paused" Nov 20 18:52:41 volumio volumio[1115]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:6fyEl50wOCu6RI4AWaq7Og","play_origin":"go-librespot"}} Nov 20 18:52:41 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:41 volumio volumio[1115]: SPOTIFY: {"status":"pause","service":"spop","title":"Biberlied (Zähneputzen)","artist":"Randale","album":"Tierparklieder aus Olderdissen","albumart":"https://i.scdn.co/image/ab67616d00001e0228627202d41cb2661577b3df","uri":"spotify:track:6fyEl50wOCu6RI4AWaq7Og","trackType":"spotify","seek":0,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:41 volumio volumio[1115]: info: CorePlayQueue::getTrack 10 Nov 20 18:52:41 volumio volumio[1115]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Biberlied (Zähneputzen)","artist":"Randale","album":"Tierparklieder aus Olderdissen","albumart":"https://i.scdn.co/image/ab67616d00001e0228627202d41cb2661577b3df","uri":"spotify:track:6fyEl50wOCu6RI4AWaq7Og","trackType":"spotify","seek":0,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:41 volumio volumio[1115]: verbose: CURRENT POSITION 10 Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::syncState stateService pause Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Nov 20 18:52:41 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:41 volumio volumio[1115]: info: CorePlayQueue::getTrack 10 Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:41 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:42 volumio volumio[1115]: info: CoreStateMachine::play index undefined Nov 20 18:52:42 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:52:42 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:42 volumio volumio[1115]: info: CoreStateMachine::startPlaybackTimer Nov 20 18:52:42 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:42 volumio volumio[1115]: info: [1732125162073] ControllerSpotify::clearAddPlayTrack Nov 20 18:52:42 volumio volumio[1115]: info: Sending Spotify command with payload to local API: /player/play Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=debug msg="loading track spotify:track:0w0EXEdIRdcCpFiRFXigcd (paused: false, position: 0ms)" Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=trace msg="emitting websocket event: will_play" Nov 20 18:52:42 volumio volumio[1115]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","play_origin":"go-librespot"}} Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:0w0EXEdIRdcCpFiRFXigcd" Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=debug msg="requested aes key for file 43aad5714bece5ca60ceaa5c3ef7ce12e00c6a93, gid: 0w0EXEdIRdcCpFiRFXigcd" Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=debug msg="fetched first chunk of 11, total size is 5696508 bytes" Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Nov 20 18:52:42 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=info msg="loaded track \"Drei rote Autos\" (uri: spotify:track:0w0EXEdIRdcCpFiRFXigcd, paused: false, position: 0ms, duration: 129653ms)" Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=debug msg="fetched chunk 2/10, size: 524288" Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=trace msg="emitting websocket event: metadata" Nov 20 18:52:42 volumio volumio[1115]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","name":"Drei rote Autos","artist_names":["Randale"],"album_name":"Tatü Tata - Drei Feuerwehrlieder","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","position":0,"duration":129653,"release_date":"year:2010 month:6 day:1","track_number":2,"disc_number":1}} Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=debug msg="fetched chunk 1/10, size: 524288" Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=trace msg="emitting websocket event: playing" Nov 20 18:52:42 volumio volumio[1115]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","play_origin":"go-librespot"}} Nov 20 18:52:42 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:42 volumio volumio[1115]: SPOTIFY: {"status":"play","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:42 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:42 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:42 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:42 volumio volumio[1115]: verbose: CURRENT POSITION 3 Nov 20 18:52:42 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Nov 20 18:52:42 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Nov 20 18:52:42 volumio go-librespot[1336]: time="2024-11-20T18:52:42+01:00" level=debug msg="fetched chunk 3/10, size: 524288" Nov 20 18:52:42 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:42 volumio volumio[1115]: SPOTIFY: {"status":"play","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:42 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:42 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:42 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:42 volumio volumio[1115]: verbose: CURRENT POSITION 3 Nov 20 18:52:42 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Nov 20 18:52:42 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus play Nov 20 18:52:42 volumio volumio[1115]: info: Received an update from plugin. extracting info from payload Nov 20 18:52:42 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:42 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:42 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:42 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:42 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:42 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:42 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:43 volumio volumio[1115]: info: CoreCommandRouter::volumioNext Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::next Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::stop Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::stPlaybackTimer Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Nov 20 18:52:43 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:43 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:43 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:43 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::serviceStop Nov 20 18:52:43 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:43 volumio volumio[1115]: info: CoreCommandRouter::serviceStop Nov 20 18:52:43 volumio volumio[1115]: info: Spotify Stop Nov 20 18:52:43 volumio volumio[1115]: SPOTIFY: SPOTIFY STOP Nov 20 18:52:43 volumio volumio[1115]: SPOTIFY: {"status":"play","position":3,"title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d0000b273ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","codec":"ogg","seek":0,"duration":129,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Nov 20 18:52:43 volumio volumio[1115]: info: Sending Spotify command to local API: /player/pause Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::play index undefined Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:52:43 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::startPlaybackTimer Nov 20 18:52:43 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:43 volumio volumio[1115]: info: [1732125163121] ControllerSpotify::clearAddPlayTrack Nov 20 18:52:43 volumio volumio[1115]: info: Sending Spotify command with payload to local API: /player/play Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Nov 20 18:52:43 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Nov 20 18:52:43 volumio volumio[1115]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 20 18:52:43 volumio ntpd[863]: 212.18.3.19 local addr 192.168.178.52 -> Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=debug msg="pause track at 1276ms" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=debug msg="loading track spotify:track:0w0EXEdIRdcCpFiRFXigcd (paused: false, position: 0ms)" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=trace msg="emitting websocket event: will_play" Nov 20 18:52:43 volumio volumio[1115]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","play_origin":"go-librespot"}} Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:0w0EXEdIRdcCpFiRFXigcd" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=debug msg="requested aes key for file 43aad5714bece5ca60ceaa5c3ef7ce12e00c6a93, gid: 0w0EXEdIRdcCpFiRFXigcd" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=debug msg="fetched first chunk of 11, total size is 5696508 bytes" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Nov 20 18:52:43 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=info msg="loaded track \"Drei rote Autos\" (uri: spotify:track:0w0EXEdIRdcCpFiRFXigcd, paused: false, position: 0ms, duration: 129653ms)" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=trace msg="emitting websocket event: metadata" Nov 20 18:52:43 volumio volumio[1115]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","name":"Drei rote Autos","artist_names":["Randale"],"album_name":"Tatü Tata - Drei Feuerwehrlieder","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","position":0,"duration":129653,"release_date":"year:2010 month:6 day:1","track_number":2,"disc_number":1}} Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=debug msg="fetched chunk 1/10, size: 524288" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=debug msg="fetched chunk 3/10, size: 524288" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:43 volumio go-librespot[1336]: time="2024-11-20T18:52:43+01:00" level=trace msg="emitting websocket event: paused" Nov 20 18:52:43 volumio volumio[1115]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","play_origin":"go-librespot"}} Nov 20 18:52:43 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:43 volumio volumio[1115]: SPOTIFY: {"status":"pause","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:43 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:43 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:43 volumio volumio[1115]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:43 volumio volumio[1115]: verbose: CURRENT POSITION 3 Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::syncState stateService pause Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Nov 20 18:52:43 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:43 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:43 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:43 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:44 volumio go-librespot[1336]: time="2024-11-20T18:52:44+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:44 volumio go-librespot[1336]: time="2024-11-20T18:52:44+01:00" level=trace msg="emitting websocket event: playing" Nov 20 18:52:44 volumio volumio[1115]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","play_origin":"go-librespot"}} Nov 20 18:52:44 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:44 volumio volumio[1115]: SPOTIFY: {"status":"play","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:44 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:44 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:44 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:44 volumio volumio[1115]: verbose: CURRENT POSITION 3 Nov 20 18:52:44 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Nov 20 18:52:44 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Nov 20 18:52:44 volumio go-librespot[1336]: time="2024-11-20T18:52:44+01:00" level=debug msg="fetched chunk 2/10, size: 524288" Nov 20 18:52:44 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:44 volumio volumio[1115]: SPOTIFY: {"status":"play","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:44 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:44 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:44 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:44 volumio volumio[1115]: verbose: CURRENT POSITION 3 Nov 20 18:52:44 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Nov 20 18:52:44 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus play Nov 20 18:52:44 volumio volumio[1115]: info: Received an update from plugin. extracting info from payload Nov 20 18:52:44 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:44 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:44 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:44 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:44 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:44 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:44 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:45 volumio volumio[1115]: info: Spotify seek to: 0 Nov 20 18:52:45 volumio volumio[1115]: info: Sending Spotify command with payload to local API: /player/seek Nov 20 18:52:45 volumio go-librespot[1336]: time="2024-11-20T18:52:45+01:00" level=debug msg="seek track to 0ms" Nov 20 18:52:45 volumio go-librespot[1336]: time="2024-11-20T18:52:45+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Nov 20 18:52:45 volumio go-librespot[1336]: time="2024-11-20T18:52:45+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:45 volumio go-librespot[1336]: time="2024-11-20T18:52:45+01:00" level=trace msg="emitting websocket event: seek" Nov 20 18:52:45 volumio volumio[1115]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","position":0,"duration":129653,"play_origin":"go-librespot"}} Nov 20 18:52:45 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:45 volumio volumio[1115]: SPOTIFY: {"status":"play","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:45 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:45 volumio volumio[1115]: verbose: CURRENT POSITION 3 Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus play Nov 20 18:52:45 volumio volumio[1115]: info: Received an update from plugin. extracting info from payload Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::stop Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::stPlaybackTimer Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::serviceStop Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrack 3 Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::serviceStop Nov 20 18:52:45 volumio volumio[1115]: info: Spotify Stop Nov 20 18:52:45 volumio volumio[1115]: SPOTIFY: SPOTIFY STOP Nov 20 18:52:45 volumio volumio[1115]: SPOTIFY: {"status":"play","position":3,"title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d0000b273ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","codec":"ogg","seek":0,"duration":129,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Nov 20 18:52:45 volumio volumio[1115]: info: Sending Spotify command to local API: /player/pause Nov 20 18:52:45 volumio volumio[1115]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::serviceClearAddPlay Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::serviceClearAddPlayTracks Nov 20 18:52:45 volumio volumio[1115]: error: WARNING: No clearAddPlayTracks method for service spop Nov 20 18:52:45 volumio go-librespot[1336]: time="2024-11-20T18:52:45+01:00" level=debug msg="pause track at 488ms" Nov 20 18:52:45 volumio go-librespot[1336]: time="2024-11-20T18:52:45+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:45 volumio go-librespot[1336]: time="2024-11-20T18:52:45+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:45 volumio go-librespot[1336]: time="2024-11-20T18:52:45+01:00" level=trace msg="emitting websocket event: paused" Nov 20 18:52:45 volumio volumio[1115]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","play_origin":"go-librespot"}} Nov 20 18:52:45 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:45 volumio volumio[1115]: SPOTIFY: {"status":"pause","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Nov 20 18:52:45 volumio volumio[1115]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Drei rote Autos","artist":"Randale","album":"Tatü Tata - Drei Feuerwehrlieder","albumart":"https://i.scdn.co/image/ab67616d00001e02ce139a1939a0c683ea78aefb","uri":"spotify:track:0w0EXEdIRdcCpFiRFXigcd","trackType":"spotify","seek":0,"duration":129,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:45 volumio volumio[1115]: verbose: CURRENT POSITION 0 Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::syncState stateService pause Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::serviceClearAddPlay Nov 20 18:52:45 volumio volumio[1115]: info: CoreCommandRouter::serviceClearAddPlayTracks Nov 20 18:52:45 volumio volumio[1115]: error: WARNING: No clearAddPlayTracks method for service spop Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::play index undefined Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrack 4 Nov 20 18:52:45 volumio volumio[1115]: info: CoreStateMachine::startPlaybackTimer Nov 20 18:52:45 volumio volumio[1115]: info: CorePlayQueue::getTrack 4 Nov 20 18:52:45 volumio volumio[1115]: info: [1732125165928] ControllerSpotify::clearAddPlayTrack Nov 20 18:52:45 volumio volumio[1115]: info: Sending Spotify command with payload to local API: /player/play Nov 20 18:52:45 volumio go-librespot[1336]: time="2024-11-20T18:52:45+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Nov 20 18:52:45 volumio go-librespot[1336]: time="2024-11-20T18:52:45+01:00" level=debug msg="loading track spotify:track:6VBS67ezatgCbKVnzYK5AW (paused: false, position: 0ms)" Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=trace msg="emitting websocket event: will_play" Nov 20 18:52:46 volumio volumio[1115]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6VBS67ezatgCbKVnzYK5AW","play_origin":"go-librespot"}} Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:6VBS67ezatgCbKVnzYK5AW" Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=debug msg="requested aes key for file d40d7d78a788550e9d3db45512236fc4ea2920d7, gid: 6VBS67ezatgCbKVnzYK5AW" Nov 20 18:52:46 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:46 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:46 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Nov 20 18:52:46 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Nov 20 18:52:46 volumio volumio[1115]: info: CoreStateMachine::serviceClearAddPlay Nov 20 18:52:46 volumio volumio[1115]: info: CoreCommandRouter::serviceClearAddPlayTracks Nov 20 18:52:46 volumio volumio[1115]: error: WARNING: No clearAddPlayTracks method for service spop Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=debug msg="fetched first chunk of 21, total size is 10635132 bytes" Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Nov 20 18:52:46 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=info msg="loaded track \"Die Mumie\" (uri: spotify:track:6VBS67ezatgCbKVnzYK5AW, paused: false, position: 0ms, duration: 235164ms)" Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=error msg="failed put state after update" error="put state request failed with status 429: Too many requests for user" Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=trace msg="emitting websocket event: metadata" Nov 20 18:52:46 volumio volumio[1115]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6VBS67ezatgCbKVnzYK5AW","name":"Die Mumie","artist_names":["Randale"],"album_name":"Feuerkäfer","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029e603f7a6dad36cc13cb43ef","position":0,"duration":235164,"release_date":"year:2024 month:8 day:16","track_number":4,"disc_number":1}} Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=debug msg="fetched chunk 3/20, size: 524288" Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=debug msg="fetched chunk 1/20, size: 524288" Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=error msg="failed put state after update" error="put state request failed with status 429: Too many requests for user" Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=trace msg="emitting websocket event: playing" Nov 20 18:52:46 volumio volumio[1115]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6VBS67ezatgCbKVnzYK5AW","play_origin":"go-librespot"}} Nov 20 18:52:46 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:46 volumio volumio[1115]: SPOTIFY: {"status":"play","service":"spop","title":"Die Mumie","artist":"Randale","album":"Feuerkäfer","albumart":"https://i.scdn.co/image/ab67616d00001e029e603f7a6dad36cc13cb43ef","uri":"spotify:track:6VBS67ezatgCbKVnzYK5AW","trackType":"spotify","seek":0,"duration":235,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:46 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:46 volumio volumio[1115]: info: CorePlayQueue::getTrack 10 Nov 20 18:52:46 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Die Mumie","artist":"Randale","album":"Feuerkäfer","albumart":"https://i.scdn.co/image/ab67616d00001e029e603f7a6dad36cc13cb43ef","uri":"spotify:track:6VBS67ezatgCbKVnzYK5AW","trackType":"spotify","seek":0,"duration":235,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:46 volumio volumio[1115]: verbose: CURRENT POSITION 10 Nov 20 18:52:46 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Nov 20 18:52:46 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Nov 20 18:52:46 volumio go-librespot[1336]: time="2024-11-20T18:52:46+01:00" level=debug msg="fetched chunk 2/20, size: 524288" Nov 20 18:52:46 volumio volumio[1115]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:52:46 volumio volumio[1115]: SPOTIFY: {"status":"play","service":"spop","title":"Die Mumie","artist":"Randale","album":"Feuerkäfer","albumart":"https://i.scdn.co/image/ab67616d00001e029e603f7a6dad36cc13cb43ef","uri":"spotify:track:6VBS67ezatgCbKVnzYK5AW","trackType":"spotify","seek":0,"duration":235,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:46 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Nov 20 18:52:46 volumio volumio[1115]: info: CorePlayQueue::getTrack 10 Nov 20 18:52:46 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Die Mumie","artist":"Randale","album":"Feuerkäfer","albumart":"https://i.scdn.co/image/ab67616d00001e029e603f7a6dad36cc13cb43ef","uri":"spotify:track:6VBS67ezatgCbKVnzYK5AW","trackType":"spotify","seek":0,"duration":235,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:52:46 volumio volumio[1115]: verbose: CURRENT POSITION 10 Nov 20 18:52:46 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Nov 20 18:52:46 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus play Nov 20 18:52:46 volumio volumio[1115]: info: Received an update from plugin. extracting info from payload Nov 20 18:52:46 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:46 volumio volumio[1115]: info: CorePlayQueue::getTrack 10 Nov 20 18:52:46 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:52:46 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:46 volumio volumio[1115]: info: CoreStateMachine::pushState Nov 20 18:52:46 volumio volumio[1115]: info: CorePlayQueue::getTrack 10 Nov 20 18:52:46 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Nov 20 18:52:50 volumio volumio[1115]: info: CoreCommandRouter::volumioPrevious Nov 20 18:52:50 volumio volumio[1115]: info: CoreStateMachine::previous Nov 20 18:52:50 volumio volumio[1115]: info: CorePlayQueue::getTrack 10 Nov 20 18:52:50 volumio volumio[1115]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 20 18:52:50 volumio volumio[1115]: TypeError: Cannot read property 'service' of undefined Nov 20 18:52:50 volumio volumio[1115]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1315:97) Nov 20 18:52:50 volumio volumio[1115]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:95:28) Nov 20 18:52:50 volumio volumio[1115]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:246:33) Nov 20 18:52:50 volumio volumio[1115]: at Socket.emit (events.js:315:20) Nov 20 18:52:50 volumio volumio[1115]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Nov 20 18:52:50 volumio volumio[1115]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 20 18:52:50 volumio volumio[1115]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 20 18:52:50 volumio sudo[6453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-20 18:51 Nov 20 18:52:50 volumio sudo[6453]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"