-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2024-11-20 18:33:32 CET. -- Nov 20 18:33:29 volumio kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Nominal byte clock 72000000; scale by 4/12 Nov 20 18:33:29 volumio volumio[1126]: info: CoreCommandRouter::volumioNext Nov 20 18:33:29 volumio volumio[1126]: info: CoreStateMachine::next Nov 20 18:33:29 volumio volumio[1126]: info: CoreStateMachine::stop Nov 20 18:33:29 volumio volumio[1126]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:33:29 volumio volumio[1126]: info: CoreStateMachine::updateTrackBlock Nov 20 18:33:29 volumio volumio[1126]: info: CorePlayQueue::getTrackBlock Nov 20 18:33:29 volumio volumio[1126]: info: CoreStateMachine::stPlaybackTimer Nov 20 18:33:29 volumio volumio[1126]: info: CoreStateMachine::pushState Nov 20 18:33:29 volumio volumio[1126]: info: CorePlayQueue::getTrack 0 Nov 20 18:33:29 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:33:29 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Nov 20 18:33:29 volumio volumio[1126]: info: CoreStateMachine::serviceStop Nov 20 18:33:29 volumio volumio[1126]: info: CorePlayQueue::getTrack 0 Nov 20 18:33:29 volumio volumio[1126]: info: CoreCommandRouter::serviceStop Nov 20 18:33:29 volumio volumio[1126]: info: Spotify Stop Nov 20 18:33:29 volumio volumio[1126]: SPOTIFY: SPOTIFY STOP Nov 20 18:33:29 volumio volumio[1126]: SPOTIFY: {"status":"pause","position":0,"title":"Geburtstagslied","artist":"Randale","album":"Hasentotenkopfpiraten","albumart":"https://i.scdn.co/image/ab67616d0000b2732ea0f7413290fe64b26b39e3","uri":"spotify:track:4s6By8HjGUxstLQR09dwho","trackType":"spotify","codec":"ogg","seek":1251,"duration":237,"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:33:29 volumio volumio[1126]: info: Sending Spotify command to local API: /player/pause Nov 20 18:33:29 volumio volumio[1126]: info: CoreStateMachine::play index undefined Nov 20 18:33:29 volumio volumio[1126]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:33:29 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:29 volumio volumio[1126]: info: CoreStateMachine::startPlaybackTimer Nov 20 18:33:29 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:29 volumio volumio[1126]: info: [1732124009854] ControllerSpotify::clearAddPlayTrack Nov 20 18:33:29 volumio volumio[1126]: info: Sending Spotify command with payload to local API: /player/play Nov 20 18:33:29 volumio volumio[1126]: info: CoreStateMachine::updateTrackBlock Nov 20 18:33:29 volumio volumio[1126]: info: CorePlayQueue::getTrackBlock Nov 20 18:33:29 volumio go-librespot[1350]: time="2024-11-20T18:33:29+01:00" level=debug msg="pause track at 3107ms" Nov 20 18:33:29 volumio go-librespot[1350]: time="2024-11-20T18:33:29+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:33:30 volumio go-librespot[1350]: time="2024-11-20T18:33:30+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:33:30 volumio go-librespot[1350]: time="2024-11-20T18:33:30+01:00" level=trace msg="emitting websocket event: paused" Nov 20 18:33:30 volumio volumio[1126]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4s6By8HjGUxstLQR09dwho","play_origin":"go-librespot"}} Nov 20 18:33:30 volumio volumio[1126]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:33:30 volumio volumio[1126]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:33:30 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Nov 20 18:33:30 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:30 volumio volumio[1126]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:33:30 volumio volumio[1126]: verbose: CURRENT POSITION 5 Nov 20 18:33:30 volumio volumio[1126]: info: CoreStateMachine::syncState stateService pause Nov 20 18:33:30 volumio volumio[1126]: info: CoreStateMachine::syncState currentStatus stop Nov 20 18:33:30 volumio volumio[1126]: info: CoreStateMachine::pushState Nov 20 18:33:30 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:30 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:33:30 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Nov 20 18:33:30 volumio go-librespot[1350]: time="2024-11-20T18:33:30+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Nov 20 18:33:30 volumio go-librespot[1350]: time="2024-11-20T18:33:30+01:00" level=debug msg="loading track spotify:track:5vcl8OnXIzyImoLxyjJAhI (paused: false, position: 0ms)" Nov 20 18:33:30 volumio go-librespot[1350]: time="2024-11-20T18:33:30+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:33:30 volumio go-librespot[1350]: time="2024-11-20T18:33:30+01:00" level=trace msg="emitting websocket event: will_play" Nov 20 18:33:30 volumio volumio[1126]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","play_origin":"go-librespot"}} Nov 20 18:33:30 volumio go-librespot[1350]: time="2024-11-20T18:33:30+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:5vcl8OnXIzyImoLxyjJAhI" Nov 20 18:33:30 volumio go-librespot[1350]: time="2024-11-20T18:33:30+01:00" level=debug msg="requested aes key for file 58d1a2aa7451c76bdb95be9f30225bd526adccb5, gid: 5vcl8OnXIzyImoLxyjJAhI" Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=debug msg="fetched first chunk of 16, total size is 8260444 bytes" Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Nov 20 18:33:31 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=info msg="loaded track \"Der Hardrockhase Harald\" (uri: spotify:track:5vcl8OnXIzyImoLxyjJAhI, paused: false, position: 0ms, duration: 213066ms)" Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=trace msg="emitting websocket event: metadata" Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","name":"Der Hardrockhase Harald","artist_names":["Randale"],"album_name":"Der Hardrockhase Harald","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02df63db7be37fb7faeb825538","position":0,"duration":213066,"release_date":"year:2009 month:12 day:4","track_number":5,"disc_number":1}} Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=debug msg="fetched chunk 1/15, size: 524288" Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=trace msg="emitting websocket event: playing" Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","play_origin":"go-librespot"}} Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: {"status":"play","service":"spop","title":"Der Hardrockhase Harald","artist":"Randale","album":"Der Hardrockhase Harald","albumart":"https://i.scdn.co/image/ab67616d00001e02df63db7be37fb7faeb825538","uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Der Hardrockhase Harald","artist":"Randale","album":"Der Hardrockhase Harald","albumart":"https://i.scdn.co/image/ab67616d00001e02df63db7be37fb7faeb825538","uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:33:31 volumio volumio[1126]: verbose: CURRENT POSITION 5 Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::syncState stateService play Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::syncState currentStatus stop Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=debug msg="fetched chunk 2/15, size: 524288" Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=debug msg="fetched chunk 3/15, size: 524288" Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::volumioPrevious Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::previous Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: info: Spotify seek to: 0 Nov 20 18:33:31 volumio volumio[1126]: info: Sending Spotify command with payload to local API: /player/seek Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=debug msg="seek track to 0ms" Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: {"status":"play","service":"spop","title":"Der Hardrockhase Harald","artist":"Randale","album":"Der Hardrockhase Harald","albumart":"https://i.scdn.co/image/ab67616d00001e02df63db7be37fb7faeb825538","uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Der Hardrockhase Harald","artist":"Randale","album":"Der Hardrockhase Harald","albumart":"https://i.scdn.co/image/ab67616d00001e02df63db7be37fb7faeb825538","uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:33:31 volumio volumio[1126]: verbose: CURRENT POSITION 5 Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::syncState stateService play Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::syncState currentStatus play Nov 20 18:33:31 volumio volumio[1126]: info: Received an update from plugin. extracting info from payload Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::pushState Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::pushState Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=trace msg="emitting websocket event: seek" Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","position":0,"duration":213066,"play_origin":"go-librespot"}} Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: {"status":"play","service":"spop","title":"Der Hardrockhase Harald","artist":"Randale","album":"Der Hardrockhase Harald","albumart":"https://i.scdn.co/image/ab67616d00001e02df63db7be37fb7faeb825538","uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Der Hardrockhase Harald","artist":"Randale","album":"Der Hardrockhase Harald","albumart":"https://i.scdn.co/image/ab67616d00001e02df63db7be37fb7faeb825538","uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:33:31 volumio volumio[1126]: verbose: CURRENT POSITION 5 Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::syncState stateService play Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::syncState currentStatus play Nov 20 18:33:31 volumio volumio[1126]: info: Received an update from plugin. extracting info from payload Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::pushState Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::pushState Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::volumioPrevious Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::previous Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::stop Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::stPlaybackTimer Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::updateTrackBlock Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrackBlock Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::pushState Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::serviceStop Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::serviceStop Nov 20 18:33:31 volumio volumio[1126]: info: Spotify Stop Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: SPOTIFY STOP Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: {"status":"play","position":5,"title":"Der Hardrockhase Harald","artist":"Randale","album":"Der Hardrockhase Harald","albumart":"https://i.scdn.co/image/ab67616d0000b273df63db7be37fb7faeb825538","uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","trackType":"spotify","codec":"ogg","seek":0,"duration":213,"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:33:31 volumio volumio[1126]: info: Sending Spotify command to local API: /player/pause Nov 20 18:33:31 volumio volumio[1126]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=debug msg="pause track at 491ms" Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:33:31 volumio go-librespot[1350]: time="2024-11-20T18:33:31+01:00" level=trace msg="emitting websocket event: paused" Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","play_origin":"go-librespot"}} Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:33:31 volumio volumio[1126]: SPOTIFY: {"status":"pause","service":"spop","title":"Der Hardrockhase Harald","artist":"Randale","album":"Der Hardrockhase Harald","albumart":"https://i.scdn.co/image/ab67616d00001e02df63db7be37fb7faeb825538","uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Der Hardrockhase Harald","artist":"Randale","album":"Der Hardrockhase Harald","albumart":"https://i.scdn.co/image/ab67616d00001e02df63db7be37fb7faeb825538","uri":"spotify:track:5vcl8OnXIzyImoLxyjJAhI","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:33:31 volumio volumio[1126]: verbose: CURRENT POSITION 5 Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::syncState stateService pause Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::syncState currentStatus stop Nov 20 18:33:31 volumio volumio[1126]: info: CoreStateMachine::pushState Nov 20 18:33:31 volumio volumio[1126]: info: CorePlayQueue::getTrack 5 Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 18:33:31 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Nov 20 18:33:32 volumio volumio[1126]: info: CoreStateMachine::play index undefined Nov 20 18:33:32 volumio volumio[1126]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 18:33:32 volumio volumio[1126]: info: CorePlayQueue::getTrack 7 Nov 20 18:33:32 volumio volumio[1126]: info: CoreStateMachine::startPlaybackTimer Nov 20 18:33:32 volumio volumio[1126]: info: CorePlayQueue::getTrack 7 Nov 20 18:33:32 volumio volumio[1126]: info: [1732124012194] ControllerSpotify::clearAddPlayTrack Nov 20 18:33:32 volumio volumio[1126]: info: Sending Spotify command with payload to local API: /player/play Nov 20 18:33:32 volumio volumio[1126]: info: CoreCommandRouter::volumioPrevious Nov 20 18:33:32 volumio volumio[1126]: info: CoreStateMachine::previous Nov 20 18:33:32 volumio volumio[1126]: info: CoreStateMachine::updateTrackBlock Nov 20 18:33:32 volumio volumio[1126]: info: CorePlayQueue::getTrackBlock Nov 20 18:33:32 volumio volumio[1126]: info: CoreStateMachine::serviceClearAddPlay Nov 20 18:33:32 volumio volumio[1126]: info: CoreCommandRouter::serviceClearAddPlayTracks Nov 20 18:33:32 volumio volumio[1126]: error: WARNING: No clearAddPlayTracks method for service spop Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=debug msg="loading track spotify:track:6Lwz5VZ8n22JbxLaKqDVeg (paused: false, position: 0ms)" Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=trace msg="emitting websocket event: will_play" Nov 20 18:33:32 volumio volumio[1126]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6Lwz5VZ8n22JbxLaKqDVeg","play_origin":"go-librespot"}} Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:6Lwz5VZ8n22JbxLaKqDVeg" Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=debug msg="requested aes key for file 793e9cce3bd3a681bdba33aadaf03ccf3225974e, gid: 6Lwz5VZ8n22JbxLaKqDVeg" Nov 20 18:33:32 volumio volumio[1126]: info: CoreCommandRouter::volumioPrevious Nov 20 18:33:32 volumio volumio[1126]: info: CoreStateMachine::previous Nov 20 18:33:32 volumio volumio[1126]: info: CoreStateMachine::updateTrackBlock Nov 20 18:33:32 volumio volumio[1126]: info: CorePlayQueue::getTrackBlock Nov 20 18:33:32 volumio volumio[1126]: info: CoreStateMachine::serviceClearAddPlay Nov 20 18:33:32 volumio volumio[1126]: info: CoreCommandRouter::serviceClearAddPlayTracks Nov 20 18:33:32 volumio volumio[1126]: error: WARNING: No clearAddPlayTracks method for service spop Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=debug msg="fetched first chunk of 16, total size is 8085468 bytes" Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Nov 20 18:33:32 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=info msg="loaded track \"Polizei\" (uri: spotify:track:6Lwz5VZ8n22JbxLaKqDVeg, paused: false, position: 0ms, duration: 188517ms)" Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=debug msg="fetched chunk 1/15, size: 524288" Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=trace msg="emitting websocket event: metadata" Nov 20 18:33:32 volumio volumio[1126]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6Lwz5VZ8n22JbxLaKqDVeg","name":"Polizei","artist_names":["Randale"],"album_name":"Kinderkrachkiste","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025ad0fc9ad29169beae77f2e8","position":0,"duration":188517,"release_date":"year:2019 month:5 day:24","track_number":3,"disc_number":1}} Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=debug msg="fetched chunk 2/15, size: 524288" Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=debug msg="fetched chunk 3/15, size: 524288" Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 20 18:33:32 volumio go-librespot[1350]: time="2024-11-20T18:33:32+01:00" level=trace msg="emitting websocket event: playing" Nov 20 18:33:32 volumio volumio[1126]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6Lwz5VZ8n22JbxLaKqDVeg","play_origin":"go-librespot"}} Nov 20 18:33:32 volumio volumio[1126]: SPOTIFY: PUSH STATE SPOTIFY Nov 20 18:33:32 volumio volumio[1126]: SPOTIFY: {"status":"play","service":"spop","title":"Polizei","artist":"Randale","album":"Kinderkrachkiste","albumart":"https://i.scdn.co/image/ab67616d00001e025ad0fc9ad29169beae77f2e8","uri":"spotify:track:6Lwz5VZ8n22JbxLaKqDVeg","trackType":"spotify","seek":0,"duration":188,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:33:32 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Nov 20 18:33:32 volumio volumio[1126]: info: CorePlayQueue::getTrack 10 Nov 20 18:33:32 volumio volumio[1126]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Polizei","artist":"Randale","album":"Kinderkrachkiste","albumart":"https://i.scdn.co/image/ab67616d00001e025ad0fc9ad29169beae77f2e8","uri":"spotify:track:6Lwz5VZ8n22JbxLaKqDVeg","trackType":"spotify","seek":0,"duration":188,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 20 18:33:32 volumio volumio[1126]: verbose: CURRENT POSITION 10 Nov 20 18:33:32 volumio volumio[1126]: info: CoreStateMachine::syncState stateService play Nov 20 18:33:32 volumio volumio[1126]: info: CoreStateMachine::syncState currentStatus stop Nov 20 18:33:32 volumio volumio[1126]: info: CoreCommandRouter::volumioPrevious Nov 20 18:33:32 volumio volumio[1126]: info: CoreStateMachine::previous Nov 20 18:33:32 volumio volumio[1126]: info: CorePlayQueue::getTrack 10 Nov 20 18:33:32 volumio volumio[1126]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 20 18:33:32 volumio volumio[1126]: TypeError: Cannot read property 'service' of undefined Nov 20 18:33:32 volumio volumio[1126]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1315:97) Nov 20 18:33:32 volumio volumio[1126]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:95:28) Nov 20 18:33:32 volumio volumio[1126]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:246:33) Nov 20 18:33:32 volumio volumio[1126]: at Socket.emit (events.js:315:20) Nov 20 18:33:32 volumio volumio[1126]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Nov 20 18:33:32 volumio volumio[1126]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 20 18:33:32 volumio volumio[1126]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 20 18:33:32 volumio sudo[15633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-20 18:32 Nov 20 18:33:32 volumio sudo[15633]: 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"