-- Logs begin at Tue 2025-04-01 22:36:33 CEST, end at Wed 2025-05-07 23:20:13 CEST. -- May 07 23:19:10 volumio go-librespot[23297]: time="2025-05-07T23:19:10+02:00" level=debug msg="fetched chunk 5/9, size: 524288" uri="spotify:track:7EC8ZF5cQ6LuizBZGI8j2e" May 07 23:19:11 volumio go-librespot[23297]: time="2025-05-07T23:19:11+02:00" level=trace msg="sent dealer ping" May 07 23:19:11 volumio go-librespot[23297]: time="2025-05-07T23:19:11+02:00" level=trace msg="received dealer pong" May 07 23:19:33 volumio volumio[3177]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 07 23:19:33 volumio volumio[3177]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:0Xmuv5wC72I4HGvV5rYiLp May 07 23:19:34 volumio volumio[3177]: info: Preload queue cleared May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:2d8D7uk3tbAThjRkdfrx9c May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:5LgPvyGuFSWz3EPlBWHSSj May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:7oNmc0UeW1k06uXdWKYJjz May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:1t2QPpzg5Iw7XX3jFxaCw9 May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:04QRovftKHqwVP1dDqgp0Q May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:0cbO1svmPpIcv9QaLLOFU3 May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:0jLyCYh76g9DRnZyh7XwDJ May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:60yO7dzq2frzIuo8Q9Xhj4 May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:2oYrU3m2zqx8w6AiHaHyau May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:4MjnkyUpVuBIEWNYXUQm3E May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:0VzJ7uXX1cNgp7ix1uDPf9 May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:6av64kCoIEg7DTOENUVcCW May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:18WCZcjOJhyjYa941pPXUt May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:7H98Au1bUKIMwkkB6aTzur May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:52QUZq9VftSTNDiEVIoso4 May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:6cj9goiARoOVvvj66qZmSf May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:5c3jKY11YG61EsFfw6ZHxN May 07 23:19:34 volumio volumio[3177]: info: Preloading song: spotify:track:3qhpXnJ1Yf6mDH9XUrlUPK May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:2d8D7uk3tbAThjRkdfrx9c in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:2d8D7uk3tbAThjRkdfrx9c May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:5LgPvyGuFSWz3EPlBWHSSj in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:5LgPvyGuFSWz3EPlBWHSSj May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:7oNmc0UeW1k06uXdWKYJjz in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:7oNmc0UeW1k06uXdWKYJjz May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:1t2QPpzg5Iw7XX3jFxaCw9 in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:1t2QPpzg5Iw7XX3jFxaCw9 May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:04QRovftKHqwVP1dDqgp0Q in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:04QRovftKHqwVP1dDqgp0Q May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:0cbO1svmPpIcv9QaLLOFU3 in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:0cbO1svmPpIcv9QaLLOFU3 May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:0jLyCYh76g9DRnZyh7XwDJ in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:0jLyCYh76g9DRnZyh7XwDJ May 07 23:19:34 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5LgPvyGuFSWz3EPlBWHSSj","service":"spop","name":"Песня без слов","artist":"Kino","album":"Звезда по имени Солнце","type":"song","duration":303,"albumart":"https://i.scdn.co/image/ab67616d0000b27394515ed61119b8543431ea89","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:34 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","service":"spop","name":"Voyage voyage","artist":"Desireless","album":"François","type":"song","duration":266,"albumart":"https://i.scdn.co/image/ab67616d0000b2738f5813c842cb115f3cf1fecd","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:60yO7dzq2frzIuo8Q9Xhj4 in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:60yO7dzq2frzIuo8Q9Xhj4 May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:2oYrU3m2zqx8w6AiHaHyau in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:2oYrU3m2zqx8w6AiHaHyau May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:4MjnkyUpVuBIEWNYXUQm3E in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:4MjnkyUpVuBIEWNYXUQm3E May 07 23:19:34 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1t2QPpzg5Iw7XX3jFxaCw9","service":"spop","name":"Поколение z","artist":"Ногу Свело!","album":"Поколение z","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b273928ba60f9cfbe442f915731d","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:0VzJ7uXX1cNgp7ix1uDPf9 in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:0VzJ7uXX1cNgp7ix1uDPf9 May 07 23:19:34 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7oNmc0UeW1k06uXdWKYJjz","service":"spop","name":"Скованные одной цепью","artist":"Nautilus Pompilius","album":"Эта музыка будет вечной. Nautilus Pompilius - 30 лет.","type":"song","duration":257,"albumart":"https://i.scdn.co/image/ab67616d0000b273f4a5fdd95d921fe1bf8c377a","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:6av64kCoIEg7DTOENUVcCW in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:6av64kCoIEg7DTOENUVcCW May 07 23:19:34 volumio volumio[3177]: info: Exploding uri spotify:track:18WCZcjOJhyjYa941pPXUt in service spop May 07 23:19:34 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:18WCZcjOJhyjYa941pPXUt May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0jLyCYh76g9DRnZyh7XwDJ","service":"spop","name":"Наши юные смешные голоса","artist":"Ногу Свело!","album":"В темноте","type":"song","duration":275,"albumart":"https://i.scdn.co/image/ab67616d0000b27313e21f77cf8724bc54ea27ed","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:04QRovftKHqwVP1dDqgp0Q","service":"spop","name":"Заебали!","artist":"Ногу Свело!","album":"Заебали!","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b2730c46d3628e2f1e65c7e4fd2f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: info: Exploding uri spotify:track:7H98Au1bUKIMwkkB6aTzur in service spop May 07 23:19:35 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:7H98Au1bUKIMwkkB6aTzur May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0cbO1svmPpIcv9QaLLOFU3","service":"spop","name":"Золотое время","artist":"Ногу Свело!","album":"4 стадии карантина","type":"song","duration":243,"albumart":"https://i.scdn.co/image/ab67616d0000b2732a40fc088d546d8bd56ee49d","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: info: Exploding uri spotify:track:52QUZq9VftSTNDiEVIoso4 in service spop May 07 23:19:35 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:52QUZq9VftSTNDiEVIoso4 May 07 23:19:35 volumio volumio[3177]: info: Exploding uri spotify:track:6cj9goiARoOVvvj66qZmSf in service spop May 07 23:19:35 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:6cj9goiARoOVvvj66qZmSf May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:60yO7dzq2frzIuo8Q9Xhj4","service":"spop","name":"Сибирская любовь","artist":"Nogu Svelo!","album":"Сибирская любовь","type":"song","duration":267,"albumart":"https://i.scdn.co/image/ab67616d0000b27305611acb52a6e8bbb9b23e40","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2oYrU3m2zqx8w6AiHaHyau","service":"spop","name":"Мясо в зубах","artist":"Nogu Svelo!","album":"Идем на восток!","type":"song","duration":260,"albumart":"https://i.scdn.co/image/ab67616d0000b273a0f83d8d6a20da1fa0775128","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0VzJ7uXX1cNgp7ix1uDPf9","service":"spop","name":"Деньги","artist":"Ногу Свело!","album":"Парфюмерия","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b2731b530aac50ad75109e6ab06e","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4MjnkyUpVuBIEWNYXUQm3E","service":"spop","name":"Украïнa","artist":"Ногу Свело!","album":"Украïнa","type":"song","duration":244,"albumart":"https://i.scdn.co/image/ab67616d0000b273073464d455dac11408ed4fd0","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: info: Exploding uri spotify:track:5c3jKY11YG61EsFfw6ZHxN in service spop May 07 23:19:35 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:5c3jKY11YG61EsFfw6ZHxN May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6av64kCoIEg7DTOENUVcCW","service":"spop","name":"Китайские колокольчики","artist":"Nogu Svelo!","album":"Счастлива, потому что беременна: синий альбом","type":"song","duration":252,"albumart":"https://i.scdn.co/image/ab67616d0000b273cb029f141c25881dfb44c078","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:18WCZcjOJhyjYa941pPXUt","service":"spop","name":"5 километров","artist":"Nogu Svelo!","album":"Съешь моё сердце","type":"song","duration":251,"albumart":"https://i.scdn.co/image/ab67616d0000b2730ff3129818e8de0177e6a95c","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: info: Exploding uri spotify:track:3qhpXnJ1Yf6mDH9XUrlUPK in service spop May 07 23:19:35 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:track:3qhpXnJ1Yf6mDH9XUrlUPK May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7H98Au1bUKIMwkkB6aTzur","service":"spop","name":"Stay Alert!","artist":"Ногу Свело!","album":"Парфюмерия","type":"song","duration":157,"albumart":"https://i.scdn.co/image/ab67616d0000b2731b530aac50ad75109e6ab06e","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6cj9goiARoOVvvj66qZmSf","service":"spop","name":"Goin To The Run","artist":"Golden Earring","album":"Very Best Of Vol. 1 - Part Two","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b273ad7196fa34f9ebe5a2898a29","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:52QUZq9VftSTNDiEVIoso4","service":"spop","name":"Сука війна!","artist":"Скрябін","album":"Сука війна!","type":"song","duration":264,"albumart":"https://i.scdn.co/image/ab67616d0000b273b2a36ba2009c2ae6ef4cf330","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5c3jKY11YG61EsFfw6ZHxN","service":"spop","name":"Ёбу дал","artist":"Пневмослон","album":"Запутай след","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b273071faeb36e11cd7e9819b1b7","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:35 volumio volumio[3177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3qhpXnJ1Yf6mDH9XUrlUPK","service":"spop","name":"Воїни світла","artist":"Lyapis Trubetskoy","album":"Воїни світла","type":"song","duration":274,"albumart":"https://i.scdn.co/image/ab67616d0000b273e1968a43c08bced126b4d3b8","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 07 23:19:37 volumio volumio[3177]: info: Preload queue cleared May 07 23:19:37 volumio volumio[3177]: info: CoreCommandRouter::volumioReplaceandPlayItems May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::ClearQueue May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::stop May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::stPlaybackTimer May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::updateTrackBlock May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::getTrackBlock May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::pushState May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:37 volumio volumio[3177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 23:19:37 volumio volumio[3177]: info: CoreCommandRouter::volumioPushState May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::serviceStop May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:37 volumio volumio[3177]: info: CoreCommandRouter::serviceStop May 07 23:19:37 volumio volumio[3177]: info: Spotify Stop May 07 23:19:37 volumio volumio[3177]: SPOTIFY: SPOTIFY STOP May 07 23:19:37 volumio volumio[3177]: SPOTIFY: {"status":"play","position":0,"title":"Колыбельная песня","artist":"Ногу Свело!","album":"В темноте","albumart":"https://i.scdn.co/image/ab67616d0000b27313e21f77cf8724bc54ea27ed","uri":"spotify:track:7EC8ZF5cQ6LuizBZGI8j2e","trackType":"spotify","codec":"ogg","seek":0,"duration":261,"samplerate":"160 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 07 23:19:37 volumio volumio[3177]: info: Sending Spotify command to local API: /player/pause May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::clearPlayQueue May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::saveQueue May 07 23:19:37 volumio volumio[3177]: info: CoreCommandRouter::volumioPushQueue May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::addQueueItems May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::addQueueItems May 07 23:19:37 volumio volumio[3177]: info: Preload queue cleared May 07 23:19:37 volumio volumio[3177]: info: Adding Item to queue: spotify:user:spotify:playlist:0Xmuv5wC72I4HGvV5rYiLp May 07 23:19:37 volumio volumio[3177]: info: Exploding uri spotify:user:spotify:playlist:0Xmuv5wC72I4HGvV5rYiLp in service spop May 07 23:19:37 volumio volumio[3177]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:0Xmuv5wC72I4HGvV5rYiLp May 07 23:19:37 volumio go-librespot[23297]: time="2025-05-07T23:19:37+02:00" level=debug msg="pause track at 81076ms" May 07 23:19:37 volumio volumio[3177]: info: touch_display: Setting screensaver timeout to 120 seconds. May 07 23:19:37 volumio go-librespot[23297]: time="2025-05-07T23:19:37+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 07 23:19:37 volumio go-librespot[23297]: time="2025-05-07T23:19:37+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 07 23:19:37 volumio go-librespot[23297]: time="2025-05-07T23:19:37+02:00" level=trace msg="emitting websocket event: paused" May 07 23:19:37 volumio volumio[3177]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7EC8ZF5cQ6LuizBZGI8j2e","play_origin":"go-librespot"}} May 07 23:19:37 volumio volumio[3177]: SPOTIFY: PUSH STATE SPOTIFY May 07 23:19:37 volumio volumio[3177]: SPOTIFY: {"status":"pause","service":"spop","title":"Колыбельная песня","artist":"Ногу Свело!","album":"В темноте","albumart":"https://i.scdn.co/image/ab67616d00001e0213e21f77cf8724bc54ea27ed","uri":"spotify:track:7EC8ZF5cQ6LuizBZGI8j2e","trackType":"spotify","seek":80000,"duration":261,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:37 volumio volumio[3177]: info: CoreCommandRouter::servicePushState May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:37 volumio volumio[3177]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Колыбельная песня","artist":"Ногу Свело!","album":"В темноте","albumart":"https://i.scdn.co/image/ab67616d00001e0213e21f77cf8724bc54ea27ed","uri":"spotify:track:7EC8ZF5cQ6LuizBZGI8j2e","trackType":"spotify","seek":80000,"duration":261,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:37 volumio volumio[3177]: verbose: CURRENT POSITION 0 May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::syncState stateService pause May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::syncState currentStatus stop May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::pushState May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:37 volumio volumio[3177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 23:19:37 volumio volumio[3177]: info: CoreCommandRouter::volumioPushState May 07 23:19:37 volumio volumio[3177]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7EC8ZF5cQ6LuizBZGI8j2e","play_origin":"go-librespot"}} May 07 23:19:37 volumio volumio[3177]: SPOTIFY: PUSH STATE SPOTIFY May 07 23:19:37 volumio volumio[3177]: SPOTIFY: {"status":"pause","service":"spop","title":"Колыбельная песня","artist":"Ногу Свело!","album":"В темноте","albumart":"https://i.scdn.co/image/ab67616d00001e0213e21f77cf8724bc54ea27ed","uri":"spotify:track:7EC8ZF5cQ6LuizBZGI8j2e","trackType":"spotify","seek":80000,"duration":261,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:37 volumio volumio[3177]: info: CoreCommandRouter::servicePushState May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:37 volumio volumio[3177]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Колыбельная песня","artist":"Ногу Свело!","album":"В темноте","albumart":"https://i.scdn.co/image/ab67616d00001e0213e21f77cf8724bc54ea27ed","uri":"spotify:track:7EC8ZF5cQ6LuizBZGI8j2e","trackType":"spotify","seek":80000,"duration":261,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:37 volumio volumio[3177]: verbose: CURRENT POSITION 0 May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::syncState stateService pause May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::syncState currentStatus stop May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::pushState May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:37 volumio volumio[3177]: info: CoreCommandRouter::volumioPushState May 07 23:19:37 volumio volumio[3177]: info: CoreCommandRouter::volumioPushQueue May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::saveQueue May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::updateTrackBlock May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::getTrackBlock May 07 23:19:37 volumio volumio[3177]: info: CoreCommandRouter::volumioPlay May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::play index 0 May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::stop May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::play index undefined May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:37 volumio volumio[3177]: info: CoreStateMachine::startPlaybackTimer May 07 23:19:37 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:37 volumio volumio[3177]: info: [1746652777957] ControllerSpotify::clearAddPlayTrack May 07 23:19:37 volumio volumio[3177]: info: Sending Spotify command with payload to local API: /player/play May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=debug msg="resolved context of track" uri="spotify:track:2d8D7uk3tbAThjRkdfrx9c" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:2d8D7uk3tbAThjRkdfrx9c" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:2d8D7uk3tbAThjRkdfrx9c" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=trace msg="emitting websocket event: will_play" May 07 23:19:38 volumio volumio[3177]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","play_origin":"go-librespot"}} May 07 23:19:38 volumio volumio[3177]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","play_origin":"go-librespot"}} May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=debug msg="selected format OGG_VORBIS_160 (576bdf92503f43650178e9e4edd3130c78f4eb05)" uri="spotify:track:2d8D7uk3tbAThjRkdfrx9c" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=debug msg="requested aes key for file 576bdf92503f43650178e9e4edd3130c78f4eb05, gid: 2d8D7uk3tbAThjRkdfrx9c" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=debug msg="fetched first chunk of 11, total size is 5530488 bytes" uri="spotify:track:2d8D7uk3tbAThjRkdfrx9c" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=trace msg="seek to 1ms (diff: -1106ms, samples: 44, bytes: 0)" uri="spotify:track:2d8D7uk3tbAThjRkdfrx9c" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" May 07 23:19:38 volumio volumio[3177]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 23:19:38 volumio volumio[3177]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 23:19:38 volumio volumio[3177]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=info msg="loaded track \"Voyage voyage\" (paused: false, position: 1ms, duration: 266600ms, prefetched: false)" uri="spotify:track:2d8D7uk3tbAThjRkdfrx9c" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:2d8D7uk3tbAThjRkdfrx9c" May 07 23:19:38 volumio volumio[3177]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 07 23:19:38 volumio volumio[3177]: info: FusionDsp - ---- read samplerate from file: 44100 May 07 23:19:38 volumio volumio[3177]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 23:19:38 volumio volumio[3177]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 07 23:19:38 volumio volumio[3177]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=trace msg="scheduling prefetch in 236s" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=trace msg="emitting websocket event: metadata" May 07 23:19:38 volumio volumio[3177]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","name":"Voyage voyage","artist_names":["Desireless"],"album_name":"François","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","position":1,"duration":266600,"release_date":"year:2001 month:1 day:2","track_number":1,"disc_number":1}} May 07 23:19:38 volumio volumio[3177]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","name":"Voyage voyage","artist_names":["Desireless"],"album_name":"François","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","position":1,"duration":266600,"release_date":"year:2001 month:1 day:2","track_number":1,"disc_number":1}} May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=debug msg="fetched chunk 1/10, size: 524288" uri="spotify:track:2d8D7uk3tbAThjRkdfrx9c" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=trace msg="emitting websocket event: playing" May 07 23:19:38 volumio volumio[3177]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","play_origin":"go-librespot"}} May 07 23:19:38 volumio volumio[3177]: SPOTIFY: PUSH STATE SPOTIFY May 07 23:19:38 volumio volumio[3177]: SPOTIFY: {"status":"play","service":"spop","title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","seek":1,"duration":266,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:38 volumio volumio[3177]: info: CoreCommandRouter::servicePushState May 07 23:19:38 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:38 volumio volumio[3177]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","seek":1,"duration":266,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:38 volumio volumio[3177]: verbose: CURRENT POSITION 0 May 07 23:19:38 volumio volumio[3177]: info: CoreStateMachine::syncState stateService play May 07 23:19:38 volumio volumio[3177]: info: CoreStateMachine::syncState currentStatus stop May 07 23:19:38 volumio volumio[3177]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","play_origin":"go-librespot"}} May 07 23:19:38 volumio volumio[3177]: SPOTIFY: PUSH STATE SPOTIFY May 07 23:19:38 volumio volumio[3177]: SPOTIFY: {"status":"play","service":"spop","title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","seek":1,"duration":266,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:38 volumio volumio[3177]: info: CoreCommandRouter::servicePushState May 07 23:19:38 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:38 volumio volumio[3177]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","seek":1,"duration":266,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:38 volumio volumio[3177]: verbose: CURRENT POSITION 0 May 07 23:19:38 volumio volumio[3177]: info: CoreStateMachine::syncState stateService play May 07 23:19:38 volumio volumio[3177]: info: CoreStateMachine::syncState currentStatus play May 07 23:19:38 volumio volumio[3177]: info: Received an update from plugin. extracting info from payload May 07 23:19:38 volumio volumio[3177]: info: CoreStateMachine::pushState May 07 23:19:38 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:38 volumio volumio[3177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 23:19:38 volumio volumio[3177]: info: CoreCommandRouter::volumioPushState May 07 23:19:38 volumio volumio[3177]: info: CoreStateMachine::pushState May 07 23:19:38 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:38 volumio volumio[3177]: info: CoreCommandRouter::volumioPushState May 07 23:19:38 volumio volumio[3177]: info: [LastFM] Current track has sufficient metadata: title (Voyage voyage) and artist (Desireless) passed on explicitly May 07 23:19:38 volumio volumio[3177]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) May 07 23:19:38 volumio go-librespot[23297]: time="2025-05-07T23:19:38+02:00" level=debug msg="fetched chunk 3/10, size: 524288" uri="spotify:track:2d8D7uk3tbAThjRkdfrx9c" May 07 23:19:38 volumio volumio[3177]: info: touch_display: Setting screensaver timeout to 0 seconds. May 07 23:19:39 volumio volumio[3177]: SPOTIFY: PUSH STATE SPOTIFY May 07 23:19:39 volumio volumio[3177]: SPOTIFY: {"status":"play","service":"spop","title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","seek":1,"duration":266,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:39 volumio volumio[3177]: info: CoreCommandRouter::servicePushState May 07 23:19:39 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:39 volumio volumio[3177]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","seek":1,"duration":266,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:39 volumio volumio[3177]: verbose: CURRENT POSITION 0 May 07 23:19:39 volumio volumio[3177]: info: CoreStateMachine::syncState stateService play May 07 23:19:39 volumio volumio[3177]: info: CoreStateMachine::syncState currentStatus play May 07 23:19:39 volumio volumio[3177]: info: Received an update from plugin. extracting info from payload May 07 23:19:39 volumio volumio[3177]: info: CoreStateMachine::pushState May 07 23:19:39 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:39 volumio volumio[3177]: info: CoreCommandRouter::volumioPushState May 07 23:19:39 volumio go-librespot[23297]: time="2025-05-07T23:19:39+02:00" level=debug msg="fetched chunk 2/10, size: 524288" uri="spotify:track:2d8D7uk3tbAThjRkdfrx9c" May 07 23:19:39 volumio volumio[3177]: info: CoreStateMachine::pushState May 07 23:19:39 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:39 volumio volumio[3177]: info: CoreCommandRouter::volumioPushState May 07 23:19:39 volumio volumio[3177]: SPOTIFY: PUSH STATE SPOTIFY May 07 23:19:39 volumio volumio[3177]: SPOTIFY: {"status":"play","service":"spop","title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","seek":1,"duration":266,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:39 volumio volumio[3177]: info: CoreCommandRouter::servicePushState May 07 23:19:39 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:39 volumio volumio[3177]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","seek":1,"duration":266,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:39 volumio volumio[3177]: verbose: CURRENT POSITION 0 May 07 23:19:39 volumio volumio[3177]: info: CoreStateMachine::syncState stateService play May 07 23:19:39 volumio volumio[3177]: info: CoreStateMachine::syncState currentStatus play May 07 23:19:39 volumio volumio[3177]: info: Received an update from plugin. extracting info from payload May 07 23:19:39 volumio volumio[3177]: info: CoreStateMachine::pushState May 07 23:19:39 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:39 volumio volumio[3177]: info: CoreCommandRouter::volumioPushState May 07 23:19:39 volumio volumio[3177]: info: CoreStateMachine::pushState May 07 23:19:39 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:39 volumio volumio[3177]: info: CoreCommandRouter::volumioPushState May 07 23:19:41 volumio go-librespot[23297]: time="2025-05-07T23:19:41+02:00" level=trace msg="sent dealer ping" May 07 23:19:41 volumio go-librespot[23297]: time="2025-05-07T23:19:41+02:00" level=trace msg="received dealer pong" May 07 23:19:52 volumio volumio[3177]: info: CoreCommandRouter::volumioPause May 07 23:19:52 volumio volumio[3177]: info: CoreStateMachine::pause May 07 23:19:52 volumio volumio[3177]: info: CoreStateMachine::stPlaybackTimer May 07 23:19:52 volumio volumio[3177]: info: CoreStateMachine::servicePause May 07 23:19:52 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:52 volumio volumio[3177]: info: CoreCommandRouter::servicePause May 07 23:19:52 volumio volumio[3177]: info: Spotify Received pause May 07 23:19:52 volumio volumio[3177]: SPOTIFY: SPOTIFY PAUSE May 07 23:19:52 volumio volumio[3177]: SPOTIFY: {"status":"play","position":0,"title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d0000b2738f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","codec":"ogg","seek":1,"duration":266,"samplerate":"160 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 07 23:19:52 volumio volumio[3177]: info: Sending Spotify command to local API: /player/pause May 07 23:19:52 volumio go-librespot[23297]: time="2025-05-07T23:19:52+02:00" level=debug msg="pause track at 15181ms" May 07 23:19:52 volumio go-librespot[23297]: time="2025-05-07T23:19:52+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 07 23:19:53 volumio go-librespot[23297]: time="2025-05-07T23:19:53+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 07 23:19:53 volumio go-librespot[23297]: time="2025-05-07T23:19:53+02:00" level=trace msg="emitting websocket event: paused" May 07 23:19:53 volumio volumio[3177]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","play_origin":"go-librespot"}} May 07 23:19:53 volumio volumio[3177]: SPOTIFY: PUSH STATE SPOTIFY May 07 23:19:53 volumio volumio[3177]: SPOTIFY: {"status":"pause","service":"spop","title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","seek":14001,"duration":266,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:53 volumio volumio[3177]: info: CoreCommandRouter::servicePushState May 07 23:19:53 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:53 volumio volumio[3177]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","seek":14001,"duration":266,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:53 volumio volumio[3177]: verbose: CURRENT POSITION 0 May 07 23:19:53 volumio volumio[3177]: info: CoreStateMachine::syncState stateService pause May 07 23:19:53 volumio volumio[3177]: info: CoreStateMachine::syncState currentStatus pause May 07 23:19:53 volumio volumio[3177]: info: CoreStateMachine::pushState May 07 23:19:53 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:53 volumio volumio[3177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 23:19:53 volumio volumio[3177]: info: CoreCommandRouter::volumioPushState May 07 23:19:53 volumio volumio[3177]: info: CoreStateMachine::stPlaybackTimer May 07 23:19:53 volumio volumio[3177]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","play_origin":"go-librespot"}} May 07 23:19:53 volumio volumio[3177]: SPOTIFY: PUSH STATE SPOTIFY May 07 23:19:53 volumio volumio[3177]: SPOTIFY: {"status":"pause","service":"spop","title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","seek":14001,"duration":266,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:53 volumio volumio[3177]: info: CoreCommandRouter::servicePushState May 07 23:19:53 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:53 volumio volumio[3177]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Voyage voyage","artist":"Desireless","album":"François","albumart":"https://i.scdn.co/image/ab67616d00001e028f5813c842cb115f3cf1fecd","uri":"spotify:track:2d8D7uk3tbAThjRkdfrx9c","trackType":"spotify","seek":14001,"duration":266,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 07 23:19:53 volumio volumio[3177]: verbose: CURRENT POSITION 0 May 07 23:19:53 volumio volumio[3177]: info: CoreStateMachine::syncState stateService pause May 07 23:19:53 volumio volumio[3177]: info: CoreStateMachine::syncState currentStatus pause May 07 23:19:53 volumio volumio[3177]: info: CoreStateMachine::pushState May 07 23:19:53 volumio volumio[3177]: info: CorePlayQueue::getTrack 0 May 07 23:19:53 volumio volumio[3177]: info: CoreCommandRouter::volumioPushState May 07 23:19:53 volumio volumio[3177]: info: CoreStateMachine::stPlaybackTimer May 07 23:19:53 volumio volumio[3177]: info: touch_display: Setting screensaver timeout to 120 seconds. May 07 23:20:10 volumio volumio[3177]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ May 07 23:20:10 volumio volumio[3177]: info: PLUGIN onShutdown : networkfs May 07 23:20:10 volumio volumio[3177]: info: PLUGIN onShutdown : audiophonicsonoff May 07 23:20:10 volumio volumio[3177]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 23:20:10 volumio volumio[3177]: TypeError: Cannot read property 'writeSync' of undefined May 07 23:20:10 volumio volumio[3177]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_hardware/audiophonicsonoff/index.js:48:25) May 07 23:20:10 volumio volumio[3177]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) May 07 23:20:10 volumio volumio[3177]: at HashMap. (/volumio/app/pluginmanager.js:621:31) May 07 23:20:10 volumio volumio[3177]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 07 23:20:10 volumio volumio[3177]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 07 23:20:10 volumio volumio[3177]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) May 07 23:20:10 volumio volumio[3177]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1328:22) May 07 23:20:10 volumio volumio[3177]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) May 07 23:20:10 volumio volumio[3177]: at Socket.emit (events.js:315:20) May 07 23:20:10 volumio volumio[3177]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 07 23:20:10 volumio volumio[3177]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 07 23:20:10 volumio volumio[3177]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 23:20:11 volumio go-librespot[23297]: time="2025-05-07T23:20:11+02:00" level=trace msg="sent dealer ping" May 07 23:20:11 volumio go-librespot[23297]: time="2025-05-07T23:20:11+02:00" level=trace msg="received dealer pong" May 07 23:20:13 volumio sudo[23882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-07 23:19 May 07 23:20:13 volumio sudo[23882]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"