-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2024-10-16 21:18:26 CEST. -- Oct 16 21:17:16 volumio volumio[958]: Searching plugin music_service/spop Oct 16 21:17:16 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 16 21:17:17 volumio volumio[958]: info: CorePlayQueue::getTrack 4 Oct 16 21:17:17 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:17:17 volumio volumio[958]: info: Prefetching next song Oct 16 21:17:17 volumio volumio[958]: info: [1729106237443] ControllerSpotify::prefetch Oct 16 21:17:17 volumio volumio[958]: info: Sending Spotify command with payload to local API: /player/add_to_queue Oct 16 21:17:17 volumio go-librespot[1199]: time="2024-10-16T21:17:17+02:00" level=debug msg="renewing login5 access token" Oct 16 21:17:17 volumio volumio[958]: info: All search sources collected, pushing search results Oct 16 21:17:17 volumio go-librespot[1199]: time="2024-10-16T21:17:17+02:00" level=debug msg="authenticated as 31zfgz7amhs5nxbcbgjxs6kgasvq" Oct 16 21:17:18 volumio go-librespot[1199]: time="2024-10-16T21:17:18+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 21:17:18 volumio go-librespot[1199]: time="2024-10-16T21:17:18+02:00" level=debug msg="prefetching next track" uri="spotify:track:62KZRwymXq9Rgm8Cm5UCfx" Oct 16 21:17:18 volumio go-librespot[1199]: time="2024-10-16T21:17:18+02:00" level=debug msg="selected format OGG_VORBIS_320 (601fb292e857e23ff670d5014c4931b452fd08f6)" uri="spotify:track:62KZRwymXq9Rgm8Cm5UCfx" Oct 16 21:17:18 volumio go-librespot[1199]: time="2024-10-16T21:17:18+02:00" level=debug msg="requested aes key for file 601fb292e857e23ff670d5014c4931b452fd08f6, gid: 62KZRwymXq9Rgm8Cm5UCfx" Oct 16 21:17:18 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 21:17:18 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 21:17:18 volumio volumio[958]: info: Discovery: Getting this device information Oct 16 21:17:18 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Oct 16 21:17:18 volumio volumio[958]: info: CorePlayQueue::getTrack 4 Oct 16 21:17:18 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 21:17:20 volumio volumio[958]: Searching plugin music_service/spop Oct 16 21:17:20 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 16 21:17:21 volumio volumio[958]: info: CoreStateMachine::startPlaybackTimer Oct 16 21:17:21 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:17:22 volumio volumio[958]: info: CoreStateMachine::pushState Oct 16 21:17:22 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:17:22 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 21:17:22 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Oct 16 21:17:22 volumio volumio[958]: info: MRS: Pushing multiroomSync output update for this device Oct 16 21:17:22 volumio volumio[958]: info: MRS: Pushing multiroomSync output Oct 16 21:17:22 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 16 21:17:23 volumio volumio[958]: Searching plugin music_service/spop Oct 16 21:17:23 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 16 21:17:24 volumio volumio[958]: info: All search sources collected, pushing search results Oct 16 21:17:27 volumio volumio[958]: info: All search sources collected, pushing search results Oct 16 21:17:28 volumio go-librespot[1199]: time="2024-10-16T21:17:28+02:00" level=debug msg="fetched first chunk of 13, total size is 6804816 bytes" uri="spotify:track:62KZRwymXq9Rgm8Cm5UCfx" Oct 16 21:17:28 volumio go-librespot[1199]: time="2024-10-16T21:17:28+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:62KZRwymXq9Rgm8Cm5UCfx" Oct 16 21:17:28 volumio go-librespot[1199]: time="2024-10-16T21:17:28+02:00" level=info msg="prefetched track \"The World We Knew (Over And Over)\" (duration: 167053ms)" uri="spotify:track:62KZRwymXq9Rgm8Cm5UCfx" Oct 16 21:17:30 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 16 21:17:30 volumio volumio[958]: info: In handleBrowseUri, curUri=spotify:artist:4N8BwYTEC6XqykGvXXlmfv Oct 16 21:17:34 volumio go-librespot[1199]: time="2024-10-16T21:17:34+02:00" level=trace msg="emitting websocket event: not_playing" Oct 16 21:17:34 volumio go-librespot[1199]: time="2024-10-16T21:17:34+02:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:62KZRwymXq9Rgm8Cm5UCfx" Oct 16 21:17:34 volumio volumio[958]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:4FmCUATNIarCQh72JYdvnm","play_origin":"go-librespot"}} Oct 16 21:17:34 volumio volumio[958]: error: Failed to decode event: not_playing Oct 16 21:17:35 volumio go-librespot[1199]: time="2024-10-16T21:17:35+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 21:17:35 volumio go-librespot[1199]: time="2024-10-16T21:17:35+02:00" level=trace msg="emitting websocket event: will_play" Oct 16 21:17:35 volumio volumio[958]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:62KZRwymXq9Rgm8Cm5UCfx","play_origin":"go-librespot"}} Oct 16 21:17:35 volumio go-librespot[1199]: time="2024-10-16T21:17:35+02:00" level=info msg="loaded track \"The World We Knew (Over And Over)\" (paused: false, position: 1ms, duration: 167053ms, prefetched: true)" uri="spotify:track:62KZRwymXq9Rgm8Cm5UCfx" Oct 16 21:17:36 volumio go-librespot[1199]: time="2024-10-16T21:17:36+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 21:17:36 volumio go-librespot[1199]: time="2024-10-16T21:17:36+02:00" level=trace msg="scheduling prefetch in 135s" Oct 16 21:17:36 volumio go-librespot[1199]: time="2024-10-16T21:17:36+02:00" level=trace msg="emitting websocket event: metadata" Oct 16 21:17:36 volumio volumio[958]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:62KZRwymXq9Rgm8Cm5UCfx","name":"The World We Knew (Over And Over)","artist_names":["Frank Sinatra"],"album_name":"The World We Knew","album_cover_url":"https://i.scdn.co/image/ab67616d00001e023e2331ff09f59ea3c87fe5d6","position":1,"duration":167053,"release_date":"year:1967 month:8","track_number":1,"disc_number":1}} Oct 16 21:17:37 volumio go-librespot[1199]: time="2024-10-16T21:17:37+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 21:17:37 volumio go-librespot[1199]: time="2024-10-16T21:17:37+02:00" level=trace msg="emitting websocket event: playing" Oct 16 21:17:37 volumio volumio[958]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:62KZRwymXq9Rgm8Cm5UCfx","play_origin":"go-librespot"}} Oct 16 21:17:37 volumio volumio[958]: SPOTIFY: PUSH STATE SPOTIFY Oct 16 21:17:37 volumio volumio[958]: SPOTIFY: {"status":"play","service":"spop","title":"The World We Knew (Over And Over)","artist":"Frank Sinatra","album":"The World We Knew","albumart":"https://i.scdn.co/image/ab67616d00001e023e2331ff09f59ea3c87fe5d6","uri":"spotify:track:62KZRwymXq9Rgm8Cm5UCfx","trackType":"spotify","seek":1001,"duration":167,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 16 21:17:37 volumio volumio[958]: info: CoreCommandRouter::servicePushState Oct 16 21:17:37 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:17:37 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The World We Knew (Over And Over)","artist":"Frank Sinatra","album":"The World We Knew","albumart":"https://i.scdn.co/image/ab67616d00001e023e2331ff09f59ea3c87fe5d6","uri":"spotify:track:62KZRwymXq9Rgm8Cm5UCfx","trackType":"spotify","seek":1001,"duration":167,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 16 21:17:37 volumio volumio[958]: verbose: CURRENT POSITION 5 Oct 16 21:17:37 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Oct 16 21:17:37 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Oct 16 21:17:37 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Oct 16 21:17:37 volumio volumio[958]: info: CoreStateMachine::pushState Oct 16 21:17:37 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:17:37 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 21:17:37 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Oct 16 21:17:37 volumio volumio[958]: info: MRS: Pushing multiroomSync output update for this device Oct 16 21:17:37 volumio volumio[958]: info: MRS: Pushing multiroomSync output Oct 16 21:17:37 volumio volumio[958]: info: CoreStateMachine::pushState Oct 16 21:17:37 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:17:37 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Oct 16 21:17:37 volumio volumio[958]: info: MRS: Pushing multiroomSync output update for this device Oct 16 21:17:37 volumio volumio[958]: info: MRS: Pushing multiroomSync output Oct 16 21:17:37 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 16 21:17:37 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 16 21:17:37 volumio volumio[958]: SPOTIFY: PUSH STATE SPOTIFY Oct 16 21:17:37 volumio volumio[958]: SPOTIFY: {"status":"play","service":"spop","title":"The World We Knew (Over And Over)","artist":"Frank Sinatra","album":"The World We Knew","albumart":"https://i.scdn.co/image/ab67616d00001e023e2331ff09f59ea3c87fe5d6","uri":"spotify:track:62KZRwymXq9Rgm8Cm5UCfx","trackType":"spotify","seek":1001,"duration":167,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 16 21:17:37 volumio volumio[958]: info: CoreCommandRouter::servicePushState Oct 16 21:17:37 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:17:37 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The World We Knew (Over And Over)","artist":"Frank Sinatra","album":"The World We Knew","albumart":"https://i.scdn.co/image/ab67616d00001e023e2331ff09f59ea3c87fe5d6","uri":"spotify:track:62KZRwymXq9Rgm8Cm5UCfx","trackType":"spotify","seek":1001,"duration":167,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 16 21:17:37 volumio volumio[958]: verbose: CURRENT POSITION 5 Oct 16 21:17:37 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Oct 16 21:17:37 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Oct 16 21:17:37 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Oct 16 21:17:37 volumio volumio[958]: info: CoreStateMachine::pushState Oct 16 21:17:37 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:17:37 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Oct 16 21:17:37 volumio volumio[958]: info: MRS: Pushing multiroomSync output update for this device Oct 16 21:17:37 volumio volumio[958]: info: MRS: Pushing multiroomSync output Oct 16 21:17:37 volumio volumio[958]: info: CoreStateMachine::pushState Oct 16 21:17:37 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:17:37 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Oct 16 21:17:37 volumio volumio[958]: info: MRS: Pushing multiroomSync output update for this device Oct 16 21:17:37 volumio volumio[958]: info: MRS: Pushing multiroomSync output Oct 16 21:17:37 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 16 21:17:37 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 16 21:17:40 volumio volumio[958]: info: Preload queue cleared Oct 16 21:17:40 volumio volumio[958]: info: Preloading song: spotify:track:0vB4Vd6PtkJSEnWsmqATnZ Oct 16 21:17:40 volumio volumio[958]: info: Preloading song: spotify:track:62GYoGszQfROZswLee6W3O Oct 16 21:17:40 volumio volumio[958]: info: Preloading song: spotify:track:5gaUkg5JNk8c4mr2jnpX8H Oct 16 21:17:40 volumio volumio[958]: info: Preloading song: spotify:track:6a899bXlCuViXW2Qs8Rdqo Oct 16 21:17:40 volumio volumio[958]: info: Preloading song: spotify:track:1nHN3m6EzCzKAmDgwoLBMD Oct 16 21:17:40 volumio volumio[958]: info: Preloading song: spotify:track:1m3BAsNsQAaSNMD2M6vlKY Oct 16 21:17:40 volumio volumio[958]: info: Preloading song: spotify:track:50cZJ9FB4U0qDHd4hywCG0 Oct 16 21:17:40 volumio volumio[958]: info: Preloading song: spotify:track:52QRIheaefNCUA4HdEGpAo Oct 16 21:17:40 volumio volumio[958]: info: Preloading song: spotify:track:2oFxITGDiTZ1JRwP8yinRt Oct 16 21:17:40 volumio volumio[958]: info: Preloading song: spotify:track:1Gnufs5iuI3h5ow29J7tOO Oct 16 21:17:40 volumio volumio[958]: info: Preload queue cleared Oct 16 21:17:41 volumio volumio[958]: info: Executing endpoint getSimilarArtists Oct 16 21:17:41 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Oct 16 21:17:41 volumio volumio[958]: info: Executing endpoint metavolumio Oct 16 21:17:41 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 21:17:48 volumio volumio[958]: error: Failed request for metavolumio API Oct 16 21:17:49 volumio go-librespot[1199]: time="2024-10-16T21:17:49+02:00" level=debug msg="fetched chunk 3/12, size: 524288" uri="spotify:track:62KZRwymXq9Rgm8Cm5UCfx" Oct 16 21:17:50 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 21:17:50 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 21:17:50 volumio volumio[958]: info: Discovery: Getting this device information Oct 16 21:17:50 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Oct 16 21:17:50 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:17:50 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 21:17:51 volumio go-librespot[1199]: time="2024-10-16T21:17:51+02:00" level=debug msg="fetched chunk 1/12, size: 524288" uri="spotify:track:62KZRwymXq9Rgm8Cm5UCfx" Oct 16 21:17:57 volumio go-librespot[1199]: time="2024-10-16T21:17:57+02:00" level=debug msg="fetched chunk 2/12, size: 524288" uri="spotify:track:62KZRwymXq9Rgm8Cm5UCfx" Oct 16 21:18:02 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 16 21:18:02 volumio volumio[958]: info: In handleBrowseUri, curUri=spotify:album:30BTSX1HN08VpK2siBk1HX Oct 16 21:18:08 volumio volumio[958]: info: Preload queue cleared Oct 16 21:18:08 volumio volumio[958]: info: Preloading song: spotify:track:1ABXkIv9ql3gOcbqij3IaQ Oct 16 21:18:08 volumio volumio[958]: info: Preloading song: spotify:track:2r55g0J9vF9cc10kVXzOAm Oct 16 21:18:08 volumio volumio[958]: info: Preloading song: spotify:track:6T2sPWwWborP0Xiyqp1y8g Oct 16 21:18:08 volumio volumio[958]: info: Preloading song: spotify:track:6y1XCCk1rGqraNRBJdB1jA Oct 16 21:18:08 volumio volumio[958]: info: Preloading song: spotify:track:2EIVcEIoc2ly05AukMU9Zm Oct 16 21:18:08 volumio volumio[958]: info: Preloading song: spotify:track:6ACp6KIEtyaFguM4eDbjxH Oct 16 21:18:08 volumio volumio[958]: info: Preloading song: spotify:track:5LHXj9d5SJnrFdjqKnlUxY Oct 16 21:18:08 volumio volumio[958]: info: Preloading song: spotify:track:13gYoeII9FA0DEjQ7s57NR Oct 16 21:18:08 volumio volumio[958]: info: Preloading song: spotify:track:5aHyQuNVbESnHlQPH8cw7j Oct 16 21:18:08 volumio volumio[958]: info: Exploding uri spotify:track:1ABXkIv9ql3gOcbqij3IaQ in service spop Oct 16 21:18:08 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:1ABXkIv9ql3gOcbqij3IaQ Oct 16 21:18:08 volumio volumio[958]: info: Exploding uri spotify:track:2r55g0J9vF9cc10kVXzOAm in service spop Oct 16 21:18:08 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:2r55g0J9vF9cc10kVXzOAm Oct 16 21:18:08 volumio volumio[958]: info: Exploding uri spotify:track:6T2sPWwWborP0Xiyqp1y8g in service spop Oct 16 21:18:08 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:6T2sPWwWborP0Xiyqp1y8g Oct 16 21:18:08 volumio volumio[958]: info: Executing endpoint getSimilarAlbums Oct 16 21:18:08 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 16 21:18:08 volumio volumio[958]: info: Exploding uri spotify:track:6y1XCCk1rGqraNRBJdB1jA in service spop Oct 16 21:18:08 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:6y1XCCk1rGqraNRBJdB1jA Oct 16 21:18:08 volumio volumio[958]: info: Exploding uri spotify:track:2EIVcEIoc2ly05AukMU9Zm in service spop Oct 16 21:18:08 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:2EIVcEIoc2ly05AukMU9Zm Oct 16 21:18:08 volumio volumio[958]: info: Executing endpoint metavolumio Oct 16 21:18:08 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 21:18:08 volumio volumio[958]: info: Executing endpoint metavolumio Oct 16 21:18:08 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 21:18:08 volumio volumio[958]: info: Exploding uri spotify:track:6ACp6KIEtyaFguM4eDbjxH in service spop Oct 16 21:18:08 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:6ACp6KIEtyaFguM4eDbjxH Oct 16 21:18:08 volumio volumio[958]: info: Exploding uri spotify:track:5LHXj9d5SJnrFdjqKnlUxY in service spop Oct 16 21:18:08 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:5LHXj9d5SJnrFdjqKnlUxY Oct 16 21:18:08 volumio volumio[958]: info: Exploding uri spotify:track:13gYoeII9FA0DEjQ7s57NR in service spop Oct 16 21:18:08 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:13gYoeII9FA0DEjQ7s57NR Oct 16 21:18:08 volumio volumio[958]: info: Exploding uri spotify:track:5aHyQuNVbESnHlQPH8cw7j in service spop Oct 16 21:18:08 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:5aHyQuNVbESnHlQPH8cw7j Oct 16 21:18:11 volumio volumio[958]: info: Preload queue cleared Oct 16 21:18:11 volumio volumio[958]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 21:18:11 volumio volumio[958]: info: CoreStateMachine::ClearQueue Oct 16 21:18:11 volumio volumio[958]: info: CoreStateMachine::stop Oct 16 21:18:11 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 21:18:11 volumio volumio[958]: info: CoreStateMachine::stPlaybackTimer Oct 16 21:18:11 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Oct 16 21:18:11 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Oct 16 21:18:11 volumio volumio[958]: info: CoreStateMachine::pushState Oct 16 21:18:11 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:18:11 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 21:18:11 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Oct 16 21:18:11 volumio volumio[958]: info: MRS: Pushing multiroomSync output update for this device Oct 16 21:18:11 volumio volumio[958]: info: MRS: Pushing multiroomSync output Oct 16 21:18:11 volumio volumio[958]: info: CoreStateMachine::serviceStop Oct 16 21:18:11 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:18:11 volumio volumio[958]: info: CoreCommandRouter::serviceStop Oct 16 21:18:11 volumio volumio[958]: info: Spotify Stop Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: SPOTIFY STOP Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: {"status":"play","position":5,"title":"The World We Knew (Over And Over)","artist":"Frank Sinatra","album":"The World We Knew","albumart":"https://i.scdn.co/image/ab67616d0000b2733e2331ff09f59ea3c87fe5d6","uri":"spotify:track:62KZRwymXq9Rgm8Cm5UCfx","trackType":"spotify","codec":"ogg","seek":1001,"duration":167,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":87,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Oct 16 21:18:11 volumio volumio[958]: info: Sending Spotify command to local API: /player/pause Oct 16 21:18:11 volumio volumio[958]: info: CorePlayQueue::clearPlayQueue Oct 16 21:18:11 volumio volumio[958]: info: CorePlayQueue::saveQueue Oct 16 21:18:11 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Oct 16 21:18:11 volumio volumio[958]: info: CoreStateMachine::addQueueItems Oct 16 21:18:11 volumio volumio[958]: info: CorePlayQueue::addQueueItems Oct 16 21:18:11 volumio volumio[958]: info: Preload queue cleared Oct 16 21:18:11 volumio volumio[958]: info: Adding Item to queue: spotify:track:1ABXkIv9ql3gOcbqij3IaQ Oct 16 21:18:11 volumio volumio[958]: info: Exploding uri spotify:track:1ABXkIv9ql3gOcbqij3IaQ in service spop Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:1ABXkIv9ql3gOcbqij3IaQ Oct 16 21:18:11 volumio volumio[958]: info: Adding Item to queue: spotify:track:2r55g0J9vF9cc10kVXzOAm Oct 16 21:18:11 volumio volumio[958]: info: Exploding uri spotify:track:2r55g0J9vF9cc10kVXzOAm in service spop Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:2r55g0J9vF9cc10kVXzOAm Oct 16 21:18:11 volumio volumio[958]: info: Adding Item to queue: spotify:track:6T2sPWwWborP0Xiyqp1y8g Oct 16 21:18:11 volumio volumio[958]: info: Exploding uri spotify:track:6T2sPWwWborP0Xiyqp1y8g in service spop Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:6T2sPWwWborP0Xiyqp1y8g Oct 16 21:18:11 volumio volumio[958]: info: Adding Item to queue: spotify:track:6y1XCCk1rGqraNRBJdB1jA Oct 16 21:18:11 volumio volumio[958]: info: Exploding uri spotify:track:6y1XCCk1rGqraNRBJdB1jA in service spop Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:6y1XCCk1rGqraNRBJdB1jA Oct 16 21:18:11 volumio volumio[958]: info: Adding Item to queue: spotify:track:2EIVcEIoc2ly05AukMU9Zm Oct 16 21:18:11 volumio volumio[958]: info: Exploding uri spotify:track:2EIVcEIoc2ly05AukMU9Zm in service spop Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:2EIVcEIoc2ly05AukMU9Zm Oct 16 21:18:11 volumio volumio[958]: info: Adding Item to queue: spotify:track:6ACp6KIEtyaFguM4eDbjxH Oct 16 21:18:11 volumio volumio[958]: info: Exploding uri spotify:track:6ACp6KIEtyaFguM4eDbjxH in service spop Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:6ACp6KIEtyaFguM4eDbjxH Oct 16 21:18:11 volumio volumio[958]: info: Adding Item to queue: spotify:track:5LHXj9d5SJnrFdjqKnlUxY Oct 16 21:18:11 volumio volumio[958]: info: Exploding uri spotify:track:5LHXj9d5SJnrFdjqKnlUxY in service spop Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:5LHXj9d5SJnrFdjqKnlUxY Oct 16 21:18:11 volumio volumio[958]: info: Adding Item to queue: spotify:track:13gYoeII9FA0DEjQ7s57NR Oct 16 21:18:11 volumio volumio[958]: info: Exploding uri spotify:track:13gYoeII9FA0DEjQ7s57NR in service spop Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:13gYoeII9FA0DEjQ7s57NR Oct 16 21:18:11 volumio volumio[958]: info: Adding Item to queue: spotify:track:5aHyQuNVbESnHlQPH8cw7j Oct 16 21:18:11 volumio volumio[958]: info: Exploding uri spotify:track:5aHyQuNVbESnHlQPH8cw7j in service spop Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: EXPLODING URI:spotify:track:5aHyQuNVbESnHlQPH8cw7j Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 16 21:18:11 volumio volumio[958]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 16 21:18:11 volumio go-librespot[1199]: time="2024-10-16T21:18:11+02:00" level=debug msg="pause track at 31616ms" Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6y1XCCk1rGqraNRBJdB1jA","service":"spop","name":"Deeper Than You Think","artist":"George Benson","album":"Absolute Benson","type":"song","duration":354,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:11 volumio go-librespot[1199]: time="2024-10-16T21:18:11+02:00" level=debug msg="fetched chunk 4/12, size: 524288" uri="spotify:track:62KZRwymXq9Rgm8Cm5UCfx" Oct 16 21:18:11 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:13gYoeII9FA0DEjQ7s57NR","service":"spop","name":"Come Back Baby","artist":"George Benson","album":"Absolute Benson","type":"song","duration":358,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:11 volumio go-librespot[1199]: time="2024-10-16T21:18:11+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 21:18:12 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5LHXj9d5SJnrFdjqKnlUxY","service":"spop","name":"Lately","artist":"George Benson","album":"Absolute Benson","type":"song","duration":261,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:12 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1ABXkIv9ql3gOcbqij3IaQ","service":"spop","name":"The Ghetto","artist":"George Benson","album":"Absolute Benson","type":"song","duration":296,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:12 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2r55g0J9vF9cc10kVXzOAm","service":"spop","name":"El Barrio","artist":"George Benson","album":"Absolute Benson","type":"song","duration":214,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:12 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6T2sPWwWborP0Xiyqp1y8g","service":"spop","name":"Jazzenco","artist":"George Benson","album":"Absolute Benson","type":"song","duration":348,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:13 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2EIVcEIoc2ly05AukMU9Zm","service":"spop","name":"One On One","artist":"George Benson","album":"Absolute Benson","type":"song","duration":424,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:13 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6y1XCCk1rGqraNRBJdB1jA","service":"spop","name":"Deeper Than You Think","artist":"George Benson","album":"Absolute Benson","type":"song","duration":354,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:13 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2EIVcEIoc2ly05AukMU9Zm","service":"spop","name":"One On One","artist":"George Benson","album":"Absolute Benson","type":"song","duration":424,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:13 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5LHXj9d5SJnrFdjqKnlUxY","service":"spop","name":"Lately","artist":"George Benson","album":"Absolute Benson","type":"song","duration":261,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:13 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6ACp6KIEtyaFguM4eDbjxH","service":"spop","name":"Hipping The Hop","artist":"George Benson","album":"Absolute Benson","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:14 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6T2sPWwWborP0Xiyqp1y8g","service":"spop","name":"Jazzenco","artist":"George Benson","album":"Absolute Benson","type":"song","duration":348,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:14 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5aHyQuNVbESnHlQPH8cw7j","service":"spop","name":"Medicine Man","artist":"George Benson","album":"Absolute Benson","type":"song","duration":420,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:14 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5aHyQuNVbESnHlQPH8cw7j","service":"spop","name":"Medicine Man","artist":"George Benson","album":"Absolute Benson","type":"song","duration":420,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:14 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2r55g0J9vF9cc10kVXzOAm","service":"spop","name":"El Barrio","artist":"George Benson","album":"Absolute Benson","type":"song","duration":214,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:14 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:13gYoeII9FA0DEjQ7s57NR","service":"spop","name":"Come Back Baby","artist":"George Benson","album":"Absolute Benson","type":"song","duration":358,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:15 volumio go-librespot[1199]: time="2024-10-16T21:18:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 21:18:15 volumio go-librespot[1199]: time="2024-10-16T21:18:15+02:00" level=trace msg="emitting websocket event: paused" Oct 16 21:18:15 volumio volumio[958]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:62KZRwymXq9Rgm8Cm5UCfx","play_origin":"go-librespot"}} Oct 16 21:18:15 volumio volumio[958]: SPOTIFY: PUSH STATE SPOTIFY Oct 16 21:18:15 volumio volumio[958]: SPOTIFY: {"status":"pause","service":"spop","title":"The World We Knew (Over And Over)","artist":"Frank Sinatra","album":"The World We Knew","albumart":"https://i.scdn.co/image/ab67616d00001e023e2331ff09f59ea3c87fe5d6","uri":"spotify:track:62KZRwymXq9Rgm8Cm5UCfx","trackType":"spotify","seek":38001,"duration":167,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 16 21:18:15 volumio volumio[958]: info: CoreCommandRouter::servicePushState Oct 16 21:18:15 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:18:15 volumio volumio[958]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"The World We Knew (Over And Over)","artist":"Frank Sinatra","album":"The World We Knew","albumart":"https://i.scdn.co/image/ab67616d00001e023e2331ff09f59ea3c87fe5d6","uri":"spotify:track:62KZRwymXq9Rgm8Cm5UCfx","trackType":"spotify","seek":38001,"duration":167,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 16 21:18:15 volumio volumio[958]: verbose: CURRENT POSITION 5 Oct 16 21:18:15 volumio volumio[958]: info: CoreStateMachine::syncState stateService pause Oct 16 21:18:15 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Oct 16 21:18:15 volumio volumio[958]: info: CoreStateMachine::pushState Oct 16 21:18:15 volumio volumio[958]: info: CorePlayQueue::getTrack 5 Oct 16 21:18:15 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 21:18:15 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Oct 16 21:18:15 volumio volumio[958]: info: MRS: Pushing multiroomSync output update for this device Oct 16 21:18:15 volumio volumio[958]: info: MRS: Pushing multiroomSync output Oct 16 21:18:15 volumio volumio[958]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 16 21:18:15 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6ACp6KIEtyaFguM4eDbjxH","service":"spop","name":"Hipping The Hop","artist":"George Benson","album":"Absolute Benson","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:15 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Oct 16 21:18:15 volumio volumio[958]: info: CorePlayQueue::saveQueue Oct 16 21:18:15 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Oct 16 21:18:15 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Oct 16 21:18:15 volumio volumio[958]: info: CoreCommandRouter::volumioPlay Oct 16 21:18:15 volumio volumio[958]: info: CoreStateMachine::play index 0 Oct 16 21:18:15 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 21:18:15 volumio volumio[958]: info: CoreStateMachine::stop Oct 16 21:18:15 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 21:18:15 volumio volumio[958]: info: CoreStateMachine::play index undefined Oct 16 21:18:15 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 21:18:15 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Oct 16 21:18:15 volumio volumio[958]: info: CoreStateMachine::startPlaybackTimer Oct 16 21:18:15 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Oct 16 21:18:15 volumio volumio[958]: info: [1729106295045] ControllerSpotify::clearAddPlayTrack Oct 16 21:18:15 volumio volumio[958]: info: Sending Spotify command with payload to local API: /player/play Oct 16 21:18:15 volumio go-librespot[1199]: time="2024-10-16T21:18:15+02:00" level=debug msg="resolved context of track" uri="spotify:track:1ABXkIv9ql3gOcbqij3IaQ" Oct 16 21:18:15 volumio go-librespot[1199]: time="2024-10-16T21:18:15+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1ABXkIv9ql3gOcbqij3IaQ" Oct 16 21:18:15 volumio go-librespot[1199]: time="2024-10-16T21:18:15+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1ABXkIv9ql3gOcbqij3IaQ" Oct 16 21:18:15 volumio go-librespot[1199]: time="2024-10-16T21:18:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 21:18:15 volumio go-librespot[1199]: time="2024-10-16T21:18:15+02:00" level=trace msg="emitting websocket event: will_play" Oct 16 21:18:15 volumio volumio[958]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1ABXkIv9ql3gOcbqij3IaQ","play_origin":"go-librespot"}} Oct 16 21:18:15 volumio go-librespot[1199]: time="2024-10-16T21:18:15+02:00" level=debug msg="selected format OGG_VORBIS_320 (755a2982fedcff5ef2785dc917808bd180805409)" uri="spotify:track:1ABXkIv9ql3gOcbqij3IaQ" Oct 16 21:18:15 volumio go-librespot[1199]: time="2024-10-16T21:18:15+02:00" level=debug msg="requested aes key for file 755a2982fedcff5ef2785dc917808bd180805409, gid: 1ABXkIv9ql3gOcbqij3IaQ" Oct 16 21:18:18 volumio volumio[958]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1ABXkIv9ql3gOcbqij3IaQ","service":"spop","name":"The Ghetto","artist":"George Benson","album":"Absolute Benson","type":"song","duration":296,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c6fa3be6fad12d94a136ce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 21:18:18 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 21:18:18 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 21:18:18 volumio volumio[958]: info: Discovery: Getting this device information Oct 16 21:18:18 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Oct 16 21:18:18 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Oct 16 21:18:18 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 21:18:26 volumio volumio[958]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 21:18:26 volumio volumio[958]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Oct 16 21:18:26 volumio volumio[958]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Oct 16 21:18:26 volumio volumio[958]: errno: -3007, Oct 16 21:18:26 volumio volumio[958]: code: 'ENOTFOUND', Oct 16 21:18:26 volumio volumio[958]: syscall: 'getaddrinfo', Oct 16 21:18:26 volumio volumio[958]: hostname: 'lastfm.freetls.fastly.net' Oct 16 21:18:26 volumio volumio[958]: } Oct 16 21:18:26 volumio volumio[958]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 21:18:26 volumio sudo[5241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-16 21:17 Oct 16 21:18:26 volumio sudo[5241]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"