-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2024-08-31 08:57:56 CEST. -- Aug 31 08:56:08 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Aug 31 08:56:08 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Aug 31 08:56:08 volumio wpa_supplicant[989]: RRM: Ignoring radio measurement request: Not RRM network Aug 31 08:56:09 volumio go-librespot[1352]: time="2024-08-31T08:56:09+02:00" level=debug msg="fetched chunk 13/15, size: 524288" Aug 31 08:56:12 volumio wpa_supplicant[989]: RRM: Ignoring radio measurement request: Not RRM network Aug 31 08:56:12 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 31 08:56:12 volumio volumio[1119]: info: In handleBrowseUri, curUri=spotify:artist:748dDObrUoBetes0pLj788 Aug 31 08:56:13 volumio volumio[1119]: info: Preload queue cleared Aug 31 08:56:13 volumio volumio[1119]: info: Preloading song: spotify:track:0LFq9xNDSgspBQlmaFfpat Aug 31 08:56:13 volumio volumio[1119]: info: Preloading song: spotify:track:0eV2yonbivxFUtGm8RrGd8 Aug 31 08:56:13 volumio volumio[1119]: info: Preloading song: spotify:track:4BwQLePZSn9X2HoTwNpoLg Aug 31 08:56:13 volumio volumio[1119]: info: Preloading song: spotify:track:6QRng4ZFJxeRnjcnquKlx2 Aug 31 08:56:13 volumio volumio[1119]: info: Preloading song: spotify:track:3rTSoSOTvOebYMy2kzKvkJ Aug 31 08:56:13 volumio volumio[1119]: info: Preloading song: spotify:track:4ZU0CFZ51KmYf8gcwrh9av Aug 31 08:56:13 volumio volumio[1119]: info: Preloading song: spotify:track:25fSI3ShqlaA6f9QXAzPAp Aug 31 08:56:13 volumio volumio[1119]: info: Preloading song: spotify:track:1AWz2AbGsiaReKcv66lqBO Aug 31 08:56:13 volumio volumio[1119]: info: Preloading song: spotify:track:0YzLyr9resZQQdfLu61PeW Aug 31 08:56:13 volumio volumio[1119]: info: Preloading song: spotify:track:5M1Coybff67lURqcwEQZeb Aug 31 08:56:13 volumio volumio[1119]: info: Preload queue cleared Aug 31 08:56:13 volumio volumio[1119]: info: Preload queue cleared Aug 31 08:56:13 volumio volumio[1119]: info: No valid Plugin REST Endpoint Aug 31 08:56:13 volumio volumio[1119]: info: No valid Plugin REST Endpoint Aug 31 08:56:13 volumio volumio[1119]: info: No valid Plugin REST Endpoint Aug 31 08:56:13 volumio volumio[1119]: info: No valid Plugin REST Endpoint Aug 31 08:56:21 volumio go-librespot[1352]: time="2024-08-31T08:56:21+02:00" level=debug msg="fetched chunk 14/15, size: 524288" Aug 31 08:56:32 volumio go-librespot[1352]: time="2024-08-31T08:56:32+02:00" level=debug msg="fetched chunk 15/15, size: 385620" Aug 31 08:56:35 volumio volumio[1119]: Searching plugin music_service/spop Aug 31 08:56:35 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: spop , search Aug 31 08:56:36 volumio volumio[1119]: info: All search sources collected, pushing search results Aug 31 08:56:37 volumio volumio[1119]: Searching plugin music_service/spop Aug 31 08:56:37 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: spop , search Aug 31 08:56:38 volumio volumio[1119]: info: All search sources collected, pushing search results Aug 31 08:56:44 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 31 08:56:44 volumio volumio[1119]: info: In handleBrowseUri, curUri=spotify:artist:2cDWLnU6fWayFZBZK7S2lg Aug 31 08:56:44 volumio volumio[1119]: Searching plugin music_service/spop Aug 31 08:56:44 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: spop , search Aug 31 08:56:44 volumio volumio[1119]: info: Preload queue cleared Aug 31 08:56:44 volumio volumio[1119]: info: Preloading song: spotify:track:1dR7SZ4JzFHkuzoWb8ei6U Aug 31 08:56:44 volumio volumio[1119]: info: Preloading song: spotify:track:7EQtLdat1avgubjKl5AAR0 Aug 31 08:56:44 volumio volumio[1119]: info: Preloading song: spotify:track:0B6ZYglL1QS2z3bZTssiiv Aug 31 08:56:44 volumio volumio[1119]: info: Preloading song: spotify:track:1PaPvkM5vxejnwlirXNJvz Aug 31 08:56:44 volumio volumio[1119]: info: Preloading song: spotify:track:4V9wi6zwQzagY3XHRxMzlA Aug 31 08:56:44 volumio volumio[1119]: info: Preloading song: spotify:track:3Mub35THD6Shptg2VAU6FI Aug 31 08:56:44 volumio volumio[1119]: info: Preloading song: spotify:track:5jPpQrvvVGgOIsqCTq4wD2 Aug 31 08:56:44 volumio volumio[1119]: info: Preloading song: spotify:track:1SBjzMVX2NzcXjhXdZtboS Aug 31 08:56:44 volumio volumio[1119]: info: Preloading song: spotify:track:0v5Ym3BotWNE0Hrtc8eA3h Aug 31 08:56:44 volumio volumio[1119]: info: Preloading song: spotify:track:69bJYnjWnYDYjM1AaDFyId Aug 31 08:56:44 volumio volumio[1119]: info: Preload queue cleared Aug 31 08:56:44 volumio volumio[1119]: info: Preload queue cleared Aug 31 08:56:44 volumio volumio[1119]: info: No valid Plugin REST Endpoint Aug 31 08:56:44 volumio volumio[1119]: info: No valid Plugin REST Endpoint Aug 31 08:56:44 volumio volumio[1119]: info: No valid Plugin REST Endpoint Aug 31 08:56:45 volumio volumio[1119]: info: No valid Plugin REST Endpoint Aug 31 08:56:45 volumio volumio[1119]: info: All search sources collected, pushing search results Aug 31 08:57:10 volumio volumio[1119]: Searching plugin music_service/spop Aug 31 08:57:10 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: spop , search Aug 31 08:57:11 volumio volumio[1119]: info: All search sources collected, pushing search results Aug 31 08:57:18 volumio go-librespot[1352]: time="2024-08-31T08:57:18+02:00" level=trace msg="emitting websocket event: not_playing" Aug 31 08:57:18 volumio go-librespot[1352]: time="2024-08-31T08:57:18+02:00" level=debug msg="loading track spotify:track:3zodQoSA9p2jwSlFrYkzHF (paused: true, position: 0ms)" Aug 31 08:57:18 volumio volumio[1119]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:3zodQoSA9p2jwSlFrYkzHF","play_origin":"go-librespot"}} Aug 31 08:57:18 volumio volumio[1119]: error: Failed to decode event: not_playing Aug 31 08:57:18 volumio go-librespot[1352]: time="2024-08-31T08:57:18+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:18 volumio go-librespot[1352]: time="2024-08-31T08:57:18+02:00" level=trace msg="emitting websocket event: will_play" Aug 31 08:57:18 volumio volumio[1119]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3zodQoSA9p2jwSlFrYkzHF","play_origin":"go-librespot"}} Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:3zodQoSA9p2jwSlFrYkzHF" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="requested aes key for file c7b5651411215fff046fe591cd8a548b3f62512e, gid: 3zodQoSA9p2jwSlFrYkzHF" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="fetched first chunk of 16, total size is 8249940 bytes" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Aug 31 08:57:19 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=info msg="loaded track \"Tanz mit mir\" (uri: spotify:track:3zodQoSA9p2jwSlFrYkzHF, paused: true, position: 0ms, duration: 182133ms)" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="fetched chunk 3/15, size: 524288" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=trace msg="emitting websocket event: metadata" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=trace msg="emitting websocket event: stopped" Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3zodQoSA9p2jwSlFrYkzHF","name":"Tanz mit mir","artist_names":["Faun","Santiano"],"album_name":"Von den Elben","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029ca604addb297e54022ec315","position":0,"duration":182133,"release_date":"year:2013 month:1 day:1","track_number":4,"disc_number":1}} Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: PUSH STATE SPOTIFY Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: {"status":"stop","service":"spop","title":"Tanz mit mir","artist":"Faun, Santiano","album":"Von den Elben","albumart":"https://i.scdn.co/image/ab67616d00001e029ca604addb297e54022ec315","uri":"spotify:track:3zodQoSA9p2jwSlFrYkzHF","trackType":"spotify","seek":0,"duration":182,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Aug 31 08:57:19 volumio volumio[1119]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Tanz mit mir","artist":"Faun, Santiano","album":"Von den Elben","albumart":"https://i.scdn.co/image/ab67616d00001e029ca604addb297e54022ec315","uri":"spotify:track:3zodQoSA9p2jwSlFrYkzHF","trackType":"spotify","seek":0,"duration":182,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:19 volumio volumio[1119]: verbose: CURRENT POSITION 0 Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::syncState stateService stop Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::play index undefined Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::startPlaybackTimer Aug 31 08:57:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:19 volumio volumio[1119]: info: [1725087439203] ControllerSpotify::clearAddPlayTrack Aug 31 08:57:19 volumio volumio[1119]: info: Sending Spotify command with payload to local API: /player/play Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="fetched chunk 1/15, size: 524288" Aug 31 08:57:19 volumio volumio[1119]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="fetched chunk 2/15, size: 524288" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=trace msg="emitting websocket event: paused" Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3zodQoSA9p2jwSlFrYkzHF","play_origin":"go-librespot"}} Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: PUSH STATE SPOTIFY Aug 31 08:57:19 volumio volumio[1119]: 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} Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Aug 31 08:57:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:19 volumio volumio[1119]: 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} Aug 31 08:57:19 volumio volumio[1119]: verbose: CURRENT POSITION 3 Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::syncState stateService pause Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus stop Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="loading track spotify:track:4XTP6QLsMZ1GQe0c1i1Oze (paused: false, position: 0ms)" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=trace msg="emitting websocket event: will_play" Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4XTP6QLsMZ1GQe0c1i1Oze","play_origin":"go-librespot"}} Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:4XTP6QLsMZ1GQe0c1i1Oze" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="requested aes key for file ccc05c43611718fb8b8549f6842dab25731ba54f, gid: 4XTP6QLsMZ1GQe0c1i1Oze" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="fetched first chunk of 15, total size is 7834840 bytes" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Aug 31 08:57:19 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=info msg="loaded track \"Wellerman\" (uri: spotify:track:4XTP6QLsMZ1GQe0c1i1Oze, paused: false, position: 0ms, duration: 189866ms)" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="fetched chunk 2/14, size: 524288" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="fetched chunk 1/14, size: 524288" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=trace msg="emitting websocket event: metadata" Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4XTP6QLsMZ1GQe0c1i1Oze","name":"Wellerman","artist_names":["Santiano","Nathan Evans"],"album_name":"Wenn die Kälte kommt","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02d61489ad8a458a3275737de8","position":0,"duration":189866,"release_date":"year:2021 month:10 day:8","track_number":14,"disc_number":1}} Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="fetched chunk 3/14, size: 524288" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:19 volumio go-librespot[1352]: time="2024-08-31T08:57:19+02:00" level=trace msg="emitting websocket event: playing" Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4XTP6QLsMZ1GQe0c1i1Oze","play_origin":"go-librespot"}} Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: PUSH STATE SPOTIFY Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: {"status":"play","service":"spop","title":"Wellerman","artist":"Santiano, Nathan Evans","album":"Wenn die Kälte kommt","albumart":"https://i.scdn.co/image/ab67616d00001e02d61489ad8a458a3275737de8","uri":"spotify:track:4XTP6QLsMZ1GQe0c1i1Oze","trackType":"spotify","seek":0,"duration":189,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Aug 31 08:57:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:19 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Wellerman","artist":"Santiano, Nathan Evans","album":"Wenn die Kälte kommt","albumart":"https://i.scdn.co/image/ab67616d00001e02d61489ad8a458a3275737de8","uri":"spotify:track:4XTP6QLsMZ1GQe0c1i1Oze","trackType":"spotify","seek":0,"duration":189,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:19 volumio volumio[1119]: verbose: CURRENT POSITION 3 Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus stop Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: PUSH STATE SPOTIFY Aug 31 08:57:19 volumio volumio[1119]: SPOTIFY: {"status":"play","service":"spop","title":"Wellerman","artist":"Santiano, Nathan Evans","album":"Wenn die Kälte kommt","albumart":"https://i.scdn.co/image/ab67616d00001e02d61489ad8a458a3275737de8","uri":"spotify:track:4XTP6QLsMZ1GQe0c1i1Oze","trackType":"spotify","seek":0,"duration":189,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Aug 31 08:57:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:19 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Wellerman","artist":"Santiano, Nathan Evans","album":"Wenn die Kälte kommt","albumart":"https://i.scdn.co/image/ab67616d00001e02d61489ad8a458a3275737de8","uri":"spotify:track:4XTP6QLsMZ1GQe0c1i1Oze","trackType":"spotify","seek":0,"duration":189,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:19 volumio volumio[1119]: verbose: CURRENT POSITION 3 Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Aug 31 08:57:19 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:19 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:19 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:23 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 31 08:57:23 volumio volumio[1119]: info: In handleBrowseUri, curUri=spotify:album:5soXodzTvagM8nZldvbpDD Aug 31 08:57:23 volumio volumio[1119]: info: Preload queue cleared Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:3lN2lI5Zvh6JG0rVymlXDe Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:4rdyxn8agG1JlponoXBI5V Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:2MZwhuT4uiYAe8K01fJjqX Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:64aBN1shyuRJlYbNg0XXbv Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:1VRZiWi5GMIaZRqyQXBBoj Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:0a1KqaeRmOSVXOURN71TWf Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:32vuu63ih84rrdDjaQCZOJ Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:5SN35z5JRLyhg1nuFfAiT2 Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:5nT0JjU9gHk0ngKEsNZjdy Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:1hfBhMqBP4MEPTnVcVf3Qy Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:6vkNNX7fdlRDqsiQ1U077C Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:6kKlDz2q83rM25e5KBrQYX Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:2x84Q2fuZ4o46BIfIcUhyk Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:4GcRZjMTiFeJSwNLWImeWp Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:3zJxcxpLLeIwqGMf27HklJ Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:4FvbOPh8WYUNbONRDpeZSZ Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:1ZMyUHik9lrB6LAru6pPlg Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:3LD2IEZBasBM4L74FuJPZ8 Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:42BeJsWNj5Gb3XQ0JFZjrM Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:6KY988v2O01kYiYx8MRRVY Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:5giqmT14SBSUzgRxBwiStL Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:7ogfnqREE07Yg0UC5NXEYI Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:1dPpUr5XP1iYKKvxFMAYSE Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:6j6iTKg6aWYE4g7ylCDc34 Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:2rVTRWaCMz1Q1A8gM8yGAx Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:3Z7cTpTbMtqDLWAYWCRGsp Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:3GrGOmm6MPeH0F5AHb9IuG Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:5XpQ7bFb1jQ9ISWvr8R716 Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:50V0uOJVynnJgEvifnR4K9 Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:60BHvZIJMAWqfAL1Xq0TfX Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:0nfQx88WzXsvvsQhnDNsNe Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:6mzRy1LedP8l76aEo8a9dE Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:4heq0sv9wLNDIlqN4UdiDU Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:6ep7rvG4cHMOLDtwOhiZ2s Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:0WIK2O9fDkFn9tb2ygUuUU Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:5WeeRw4gxGxCJiHGY6rrPr Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:0eaekMCH93eHMLNBsXdvBM Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:4vK83k5PmuQlnQkQaZV0yV Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:4i3SFYUYAATzBKgXNX4Wfr Aug 31 08:57:23 volumio volumio[1119]: info: Preloading song: spotify:track:02TrDr4DDwo2Ox9Mg94MY4 Aug 31 08:57:23 volumio volumio[1119]: info: Exploding uri spotify:track:3lN2lI5Zvh6JG0rVymlXDe in service spop Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:3lN2lI5Zvh6JG0rVymlXDe Aug 31 08:57:23 volumio volumio[1119]: info: Exploding uri spotify:track:4rdyxn8agG1JlponoXBI5V in service spop Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:4rdyxn8agG1JlponoXBI5V Aug 31 08:57:23 volumio volumio[1119]: info: Exploding uri spotify:track:2MZwhuT4uiYAe8K01fJjqX in service spop Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:2MZwhuT4uiYAe8K01fJjqX Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3lN2lI5Zvh6JG0rVymlXDe","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 01","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":107,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:23 volumio volumio[1119]: info: No valid Plugin REST Endpoint Aug 31 08:57:23 volumio volumio[1119]: info: Exploding uri spotify:track:64aBN1shyuRJlYbNg0XXbv in service spop Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:64aBN1shyuRJlYbNg0XXbv Aug 31 08:57:23 volumio volumio[1119]: info: No valid Plugin REST Endpoint Aug 31 08:57:23 volumio volumio[1119]: info: No valid Plugin REST Endpoint Aug 31 08:57:23 volumio volumio[1119]: info: No valid Plugin REST Endpoint Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4rdyxn8agG1JlponoXBI5V","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 02","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":102,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:23 volumio volumio[1119]: info: Exploding uri spotify:track:1VRZiWi5GMIaZRqyQXBBoj in service spop Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:1VRZiWi5GMIaZRqyQXBBoj Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2MZwhuT4uiYAe8K01fJjqX","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 03","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":108,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:64aBN1shyuRJlYbNg0XXbv","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 04","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":106,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:23 volumio volumio[1119]: info: Exploding uri spotify:track:0a1KqaeRmOSVXOURN71TWf in service spop Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:0a1KqaeRmOSVXOURN71TWf Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1VRZiWi5GMIaZRqyQXBBoj","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 05","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:23 volumio volumio[1119]: info: Exploding uri spotify:track:32vuu63ih84rrdDjaQCZOJ in service spop Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:32vuu63ih84rrdDjaQCZOJ Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0a1KqaeRmOSVXOURN71TWf","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 06","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":104,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:23 volumio volumio[1119]: info: Exploding uri spotify:track:5SN35z5JRLyhg1nuFfAiT2 in service spop Aug 31 08:57:23 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:5SN35z5JRLyhg1nuFfAiT2 Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:32vuu63ih84rrdDjaQCZOJ","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 07","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":102,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:5nT0JjU9gHk0ngKEsNZjdy in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:5nT0JjU9gHk0ngKEsNZjdy Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:1hfBhMqBP4MEPTnVcVf3Qy in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:1hfBhMqBP4MEPTnVcVf3Qy Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5SN35z5JRLyhg1nuFfAiT2","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 08","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5nT0JjU9gHk0ngKEsNZjdy","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 09","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:6vkNNX7fdlRDqsiQ1U077C in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:6vkNNX7fdlRDqsiQ1U077C Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1hfBhMqBP4MEPTnVcVf3Qy","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 10","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:6kKlDz2q83rM25e5KBrQYX in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:6kKlDz2q83rM25e5KBrQYX Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6vkNNX7fdlRDqsiQ1U077C","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 11","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":104,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:2x84Q2fuZ4o46BIfIcUhyk in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:2x84Q2fuZ4o46BIfIcUhyk Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6kKlDz2q83rM25e5KBrQYX","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 12","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":111,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:4GcRZjMTiFeJSwNLWImeWp in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:4GcRZjMTiFeJSwNLWImeWp Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2x84Q2fuZ4o46BIfIcUhyk","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 13","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":103,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:3zJxcxpLLeIwqGMf27HklJ in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:3zJxcxpLLeIwqGMf27HklJ Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4GcRZjMTiFeJSwNLWImeWp","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 14","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:4FvbOPh8WYUNbONRDpeZSZ in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:4FvbOPh8WYUNbONRDpeZSZ Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3zJxcxpLLeIwqGMf27HklJ","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 15","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:1ZMyUHik9lrB6LAru6pPlg in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:1ZMyUHik9lrB6LAru6pPlg Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:3LD2IEZBasBM4L74FuJPZ8 in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:3LD2IEZBasBM4L74FuJPZ8 Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4FvbOPh8WYUNbONRDpeZSZ","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 16","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":106,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:42BeJsWNj5Gb3XQ0JFZjrM in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:42BeJsWNj5Gb3XQ0JFZjrM Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3LD2IEZBasBM4L74FuJPZ8","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 18","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":103,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:6KY988v2O01kYiYx8MRRVY in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:6KY988v2O01kYiYx8MRRVY Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1ZMyUHik9lrB6LAru6pPlg","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 17","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":108,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:5giqmT14SBSUzgRxBwiStL in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:5giqmT14SBSUzgRxBwiStL Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:42BeJsWNj5Gb3XQ0JFZjrM","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 19","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:7ogfnqREE07Yg0UC5NXEYI in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:7ogfnqREE07Yg0UC5NXEYI Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6KY988v2O01kYiYx8MRRVY","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 20","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:1dPpUr5XP1iYKKvxFMAYSE in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:1dPpUr5XP1iYKKvxFMAYSE Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5giqmT14SBSUzgRxBwiStL","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 21","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":106,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:6j6iTKg6aWYE4g7ylCDc34 in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:6j6iTKg6aWYE4g7ylCDc34 Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ogfnqREE07Yg0UC5NXEYI","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 22","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":101,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1dPpUr5XP1iYKKvxFMAYSE","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 23","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":102,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:2rVTRWaCMz1Q1A8gM8yGAx in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:2rVTRWaCMz1Q1A8gM8yGAx Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:3Z7cTpTbMtqDLWAYWCRGsp in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:3Z7cTpTbMtqDLWAYWCRGsp Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6j6iTKg6aWYE4g7ylCDc34","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 24","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":111,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2rVTRWaCMz1Q1A8gM8yGAx","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 25","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":106,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:3GrGOmm6MPeH0F5AHb9IuG in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:3GrGOmm6MPeH0F5AHb9IuG Aug 31 08:57:24 volumio volumio[1119]: info: Exploding uri spotify:track:5XpQ7bFb1jQ9ISWvr8R716 in service spop Aug 31 08:57:24 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:5XpQ7bFb1jQ9ISWvr8R716 Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3GrGOmm6MPeH0F5AHb9IuG","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 27","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: info: Exploding uri spotify:track:50V0uOJVynnJgEvifnR4K9 in service spop Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:50V0uOJVynnJgEvifnR4K9 Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Z7cTpTbMtqDLWAYWCRGsp","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 26","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":100,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5XpQ7bFb1jQ9ISWvr8R716","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 28","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":108,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: info: Exploding uri spotify:track:60BHvZIJMAWqfAL1Xq0TfX in service spop Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:60BHvZIJMAWqfAL1Xq0TfX Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:50V0uOJVynnJgEvifnR4K9","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 29","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":106,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: info: Exploding uri spotify:track:0nfQx88WzXsvvsQhnDNsNe in service spop Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:0nfQx88WzXsvvsQhnDNsNe Aug 31 08:57:25 volumio volumio[1119]: info: Exploding uri spotify:track:6mzRy1LedP8l76aEo8a9dE in service spop Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:6mzRy1LedP8l76aEo8a9dE Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:60BHvZIJMAWqfAL1Xq0TfX","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 30","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: info: Exploding uri spotify:track:4heq0sv9wLNDIlqN4UdiDU in service spop Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:4heq0sv9wLNDIlqN4UdiDU Aug 31 08:57:25 volumio volumio[1119]: info: Exploding uri spotify:track:6ep7rvG4cHMOLDtwOhiZ2s in service spop Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:6ep7rvG4cHMOLDtwOhiZ2s Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6mzRy1LedP8l76aEo8a9dE","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 32","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":101,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0nfQx88WzXsvvsQhnDNsNe","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 31","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: info: Exploding uri spotify:track:0WIK2O9fDkFn9tb2ygUuUU in service spop Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:0WIK2O9fDkFn9tb2ygUuUU Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4heq0sv9wLNDIlqN4UdiDU","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 33","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":108,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: info: Exploding uri spotify:track:5WeeRw4gxGxCJiHGY6rrPr in service spop Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:5WeeRw4gxGxCJiHGY6rrPr Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0WIK2O9fDkFn9tb2ygUuUU","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 35","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":101,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: info: Exploding uri spotify:track:0eaekMCH93eHMLNBsXdvBM in service spop Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:0eaekMCH93eHMLNBsXdvBM Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5WeeRw4gxGxCJiHGY6rrPr","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 36","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":111,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: info: Exploding uri spotify:track:4vK83k5PmuQlnQkQaZV0yV in service spop Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:4vK83k5PmuQlnQkQaZV0yV Aug 31 08:57:25 volumio volumio[1119]: info: Exploding uri spotify:track:4i3SFYUYAATzBKgXNX4Wfr in service spop Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:4i3SFYUYAATzBKgXNX4Wfr Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6ep7rvG4cHMOLDtwOhiZ2s","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 34","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0eaekMCH93eHMLNBsXdvBM","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 37","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":104,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: info: Exploding uri spotify:track:02TrDr4DDwo2Ox9Mg94MY4 in service spop Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: EXPLODING URI:spotify:track:02TrDr4DDwo2Ox9Mg94MY4 Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4vK83k5PmuQlnQkQaZV0yV","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 38","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4i3SFYUYAATzBKgXNX4Wfr","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 39","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":102,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:25 volumio volumio[1119]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:02TrDr4DDwo2Ox9Mg94MY4","service":"spop","name":"024 - Fußballstar in Gefahr - Teil 40","artist":"Die drei !!!","album":"024/Fußballstar in Gefahr","type":"song","duration":108,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d7ce687f592e8c3d3f410f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 31 08:57:32 volumio go-librespot[1352]: time="2024-08-31T08:57:32+02:00" level=debug msg="fetched chunk 4/14, size: 524288" Aug 31 08:57:34 volumio volumio[1119]: Searching plugin music_service/spop Aug 31 08:57:34 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: spop , search Aug 31 08:57:35 volumio volumio[1119]: info: All search sources collected, pushing search results Aug 31 08:57:44 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Aug 31 08:57:44 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:45 volumio go-librespot[1352]: time="2024-08-31T08:57:45+02:00" level=debug msg="fetched chunk 5/14, size: 524288" Aug 31 08:57:50 volumio volumio[1119]: info: CoreCommandRouter::volumioNext Aug 31 08:57:50 volumio volumio[1119]: info: CoreStateMachine::next Aug 31 08:57:50 volumio volumio[1119]: info: CoreStateMachine::stop Aug 31 08:57:50 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 08:57:50 volumio volumio[1119]: info: CoreStateMachine::stPlaybackTimer Aug 31 08:57:50 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock Aug 31 08:57:50 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock Aug 31 08:57:50 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:50 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:50 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:57:50 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:50 volumio volumio[1119]: info: CoreStateMachine::serviceStop Aug 31 08:57:50 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:50 volumio volumio[1119]: info: CoreCommandRouter::serviceStop Aug 31 08:57:50 volumio volumio[1119]: info: Spotify Stop Aug 31 08:57:50 volumio volumio[1119]: SPOTIFY: SPOTIFY STOP Aug 31 08:57:50 volumio volumio[1119]: SPOTIFY: {"status":"play","position":3,"title":"Wellerman","artist":"Santiano","album":"Wenn die Kälte kommt","albumart":"https://i.scdn.co/image/ab67616d0000b273d61489ad8a458a3275737de8","uri":"spotify:track:4XTP6QLsMZ1GQe0c1i1Oze","trackType":"spotify","codec":"ogg","seek":0,"duration":189,"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"} Aug 31 08:57:50 volumio volumio[1119]: info: Sending Spotify command to local API: /player/pause Aug 31 08:57:50 volumio volumio[1119]: info: CoreStateMachine::play index undefined Aug 31 08:57:50 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 08:57:50 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:50 volumio volumio[1119]: info: CoreStateMachine::startPlaybackTimer Aug 31 08:57:50 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:50 volumio volumio[1119]: info: [1725087470554] ControllerSpotify::clearAddPlayTrack Aug 31 08:57:50 volumio volumio[1119]: info: Sending Spotify command with payload to local API: /player/play Aug 31 08:57:50 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock Aug 31 08:57:50 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock Aug 31 08:57:50 volumio volumio[1119]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 31 08:57:50 volumio go-librespot[1352]: time="2024-08-31T08:57:50+02:00" level=debug msg="pause track at 31369ms" Aug 31 08:57:50 volumio go-librespot[1352]: time="2024-08-31T08:57:50+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:50 volumio go-librespot[1352]: time="2024-08-31T08:57:50+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Aug 31 08:57:50 volumio go-librespot[1352]: time="2024-08-31T08:57:50+02:00" level=debug msg="loading track spotify:track:0IYaSKrCe8DgmOGbJo3B0Y (paused: false, position: 0ms)" Aug 31 08:57:50 volumio go-librespot[1352]: time="2024-08-31T08:57:50+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:50 volumio go-librespot[1352]: time="2024-08-31T08:57:50+02:00" level=trace msg="emitting websocket event: will_play" Aug 31 08:57:50 volumio volumio[1119]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","play_origin":"go-librespot"}} Aug 31 08:57:50 volumio go-librespot[1352]: time="2024-08-31T08:57:50+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:0IYaSKrCe8DgmOGbJo3B0Y" Aug 31 08:57:50 volumio go-librespot[1352]: time="2024-08-31T08:57:50+02:00" level=debug msg="requested aes key for file 85c8ad7fa367516b743ec1d1a0e7f2e6ef858335, gid: 0IYaSKrCe8DgmOGbJo3B0Y" Aug 31 08:57:51 volumio go-librespot[1352]: time="2024-08-31T08:57:51+02:00" level=debug msg="fetched first chunk of 16, total size is 8181236 bytes" Aug 31 08:57:51 volumio go-librespot[1352]: time="2024-08-31T08:57:51+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Aug 31 08:57:51 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 31 08:57:51 volumio go-librespot[1352]: time="2024-08-31T08:57:51+02:00" level=info msg="loaded track \"Frei wie der Wind\" (uri: spotify:track:0IYaSKrCe8DgmOGbJo3B0Y, paused: false, position: 0ms, duration: 184186ms)" Aug 31 08:57:51 volumio go-librespot[1352]: time="2024-08-31T08:57:51+02:00" level=debug msg="fetched chunk 2/15, size: 524288" Aug 31 08:57:51 volumio go-librespot[1352]: time="2024-08-31T08:57:51+02:00" level=debug msg="fetched chunk 3/15, size: 524288" Aug 31 08:57:51 volumio go-librespot[1352]: time="2024-08-31T08:57:51+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:51 volumio go-librespot[1352]: time="2024-08-31T08:57:51+02:00" level=trace msg="emitting websocket event: metadata" Aug 31 08:57:51 volumio volumio[1119]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","name":"Frei wie der Wind","artist_names":["Santiano"],"album_name":"Bis ans Ende der Welt (Second Edition)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","position":0,"duration":184186,"release_date":"year:2012 month:1 day:1","track_number":2,"disc_number":1}} Aug 31 08:57:51 volumio go-librespot[1352]: time="2024-08-31T08:57:51+02:00" level=debug msg="fetched chunk 1/15, size: 524288" Aug 31 08:57:51 volumio go-librespot[1352]: time="2024-08-31T08:57:51+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:51 volumio go-librespot[1352]: time="2024-08-31T08:57:51+02:00" level=trace msg="emitting websocket event: paused" Aug 31 08:57:51 volumio volumio[1119]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","play_origin":"go-librespot"}} Aug 31 08:57:51 volumio volumio[1119]: SPOTIFY: PUSH STATE SPOTIFY Aug 31 08:57:51 volumio volumio[1119]: SPOTIFY: {"status":"pause","service":"spop","title":"Frei wie der Wind","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","trackType":"spotify","seek":0,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:51 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Aug 31 08:57:51 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:51 volumio volumio[1119]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Frei wie der Wind","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","trackType":"spotify","seek":0,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:51 volumio volumio[1119]: verbose: CURRENT POSITION 8 Aug 31 08:57:51 volumio volumio[1119]: info: CoreStateMachine::syncState stateService pause Aug 31 08:57:51 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus stop Aug 31 08:57:51 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:51 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:51 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:57:51 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:51 volumio go-librespot[1352]: time="2024-08-31T08:57:51+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:51 volumio go-librespot[1352]: time="2024-08-31T08:57:51+02:00" level=trace msg="emitting websocket event: playing" Aug 31 08:57:51 volumio volumio[1119]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","play_origin":"go-librespot"}} Aug 31 08:57:51 volumio volumio[1119]: SPOTIFY: PUSH STATE SPOTIFY Aug 31 08:57:51 volumio volumio[1119]: SPOTIFY: {"status":"play","service":"spop","title":"Frei wie der Wind","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","trackType":"spotify","seek":0,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:51 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Aug 31 08:57:51 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:51 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Frei wie der Wind","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","trackType":"spotify","seek":0,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:51 volumio volumio[1119]: verbose: CURRENT POSITION 8 Aug 31 08:57:51 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Aug 31 08:57:51 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus stop Aug 31 08:57:51 volumio volumio[1119]: SPOTIFY: PUSH STATE SPOTIFY Aug 31 08:57:51 volumio volumio[1119]: SPOTIFY: {"status":"play","service":"spop","title":"Frei wie der Wind","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","trackType":"spotify","seek":0,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:51 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Aug 31 08:57:51 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:51 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Frei wie der Wind","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","trackType":"spotify","seek":0,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:51 volumio volumio[1119]: verbose: CURRENT POSITION 8 Aug 31 08:57:51 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Aug 31 08:57:51 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Aug 31 08:57:51 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Aug 31 08:57:51 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:51 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:51 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:57:51 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:51 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:51 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:51 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:53 volumio volumio[1119]: info: CoreCommandRouter::volumioPrevious Aug 31 08:57:53 volumio volumio[1119]: info: CoreStateMachine::previous Aug 31 08:57:53 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:53 volumio volumio[1119]: info: Spotify seek to: 0 Aug 31 08:57:53 volumio volumio[1119]: info: Sending Spotify command with payload to local API: /player/seek Aug 31 08:57:53 volumio go-librespot[1352]: time="2024-08-31T08:57:53+02:00" level=debug msg="seek track to 0ms" Aug 31 08:57:53 volumio go-librespot[1352]: time="2024-08-31T08:57:53+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Aug 31 08:57:54 volumio go-librespot[1352]: time="2024-08-31T08:57:54+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:54 volumio go-librespot[1352]: time="2024-08-31T08:57:54+02:00" level=trace msg="emitting websocket event: seek" Aug 31 08:57:54 volumio volumio[1119]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","position":0,"duration":184186,"play_origin":"go-librespot"}} Aug 31 08:57:54 volumio volumio[1119]: SPOTIFY: PUSH STATE SPOTIFY Aug 31 08:57:54 volumio volumio[1119]: SPOTIFY: {"status":"play","service":"spop","title":"Frei wie der Wind","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","trackType":"spotify","seek":0,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:54 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Frei wie der Wind","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","trackType":"spotify","seek":0,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:54 volumio volumio[1119]: verbose: CURRENT POSITION 8 Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus play Aug 31 08:57:54 volumio volumio[1119]: info: Received an update from plugin. extracting info from payload Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::volumioPrevious Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::previous Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::stop Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::stPlaybackTimer Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::serviceStop Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrack 8 Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::serviceStop Aug 31 08:57:54 volumio volumio[1119]: info: Spotify Stop Aug 31 08:57:54 volumio volumio[1119]: SPOTIFY: SPOTIFY STOP Aug 31 08:57:54 volumio volumio[1119]: SPOTIFY: {"status":"play","position":8,"title":"Frei wie der Wind","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d0000b273691a46d16f733de33d7d7b0e","uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","trackType":"spotify","codec":"ogg","seek":0,"duration":184,"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"} Aug 31 08:57:54 volumio volumio[1119]: info: Sending Spotify command to local API: /player/pause Aug 31 08:57:54 volumio volumio[1119]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 31 08:57:54 volumio go-librespot[1352]: time="2024-08-31T08:57:54+02:00" level=debug msg="pause track at 459ms" Aug 31 08:57:54 volumio go-librespot[1352]: time="2024-08-31T08:57:54+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::volumioPrevious Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::previous Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::serviceClearAddPlay Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::serviceClearAddPlayTracks Aug 31 08:57:54 volumio volumio[1119]: error: WARNING: No clearAddPlayTracks method for service spop Aug 31 08:57:54 volumio go-librespot[1352]: time="2024-08-31T08:57:54+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:54 volumio go-librespot[1352]: time="2024-08-31T08:57:54+02:00" level=trace msg="emitting websocket event: paused" Aug 31 08:57:54 volumio volumio[1119]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","play_origin":"go-librespot"}} Aug 31 08:57:54 volumio volumio[1119]: SPOTIFY: PUSH STATE SPOTIFY Aug 31 08:57:54 volumio volumio[1119]: SPOTIFY: {"status":"pause","service":"spop","title":"Frei wie der Wind","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","trackType":"spotify","seek":1000,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:54 volumio volumio[1119]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Frei wie der Wind","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","uri":"spotify:track:0IYaSKrCe8DgmOGbJo3B0Y","trackType":"spotify","seek":1000,"duration":184,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:54 volumio volumio[1119]: verbose: CURRENT POSITION 3 Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::syncState stateService pause Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus stop Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::pushState Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrack 3 Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::volumioPrevious Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::previous Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::serviceClearAddPlay Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::serviceClearAddPlayTracks Aug 31 08:57:54 volumio volumio[1119]: error: WARNING: No clearAddPlayTracks method for service spop Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::play index undefined Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrack 1 Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::startPlaybackTimer Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrack 1 Aug 31 08:57:54 volumio volumio[1119]: info: [1725087474764] ControllerSpotify::clearAddPlayTrack Aug 31 08:57:54 volumio volumio[1119]: info: Sending Spotify command with payload to local API: /player/play Aug 31 08:57:54 volumio go-librespot[1352]: time="2024-08-31T08:57:54+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Aug 31 08:57:54 volumio go-librespot[1352]: time="2024-08-31T08:57:54+02:00" level=debug msg="loading track spotify:track:48iwF6sUOHYg2foDUPZWUd (paused: false, position: 0ms)" Aug 31 08:57:54 volumio go-librespot[1352]: time="2024-08-31T08:57:54+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:54 volumio go-librespot[1352]: time="2024-08-31T08:57:54+02:00" level=trace msg="emitting websocket event: will_play" Aug 31 08:57:54 volumio volumio[1119]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:48iwF6sUOHYg2foDUPZWUd","play_origin":"go-librespot"}} Aug 31 08:57:54 volumio go-librespot[1352]: time="2024-08-31T08:57:54+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:48iwF6sUOHYg2foDUPZWUd" Aug 31 08:57:54 volumio go-librespot[1352]: time="2024-08-31T08:57:54+02:00" level=debug msg="requested aes key for file 91d29384f473c7d36f39778ec6cff811a7e86e4e, gid: 48iwF6sUOHYg2foDUPZWUd" Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::volumioPrevious Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::previous Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock Aug 31 08:57:54 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock Aug 31 08:57:54 volumio volumio[1119]: info: CoreStateMachine::serviceClearAddPlay Aug 31 08:57:54 volumio volumio[1119]: info: CoreCommandRouter::serviceClearAddPlayTracks Aug 31 08:57:54 volumio volumio[1119]: error: WARNING: No clearAddPlayTracks method for service spop Aug 31 08:57:55 volumio volumio[1119]: info: CoreCommandRouter::volumioPrevious Aug 31 08:57:55 volumio volumio[1119]: info: CoreStateMachine::previous Aug 31 08:57:55 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock Aug 31 08:57:55 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock Aug 31 08:57:55 volumio volumio[1119]: info: CoreStateMachine::serviceClearAddPlay Aug 31 08:57:55 volumio volumio[1119]: info: CoreCommandRouter::serviceClearAddPlayTracks Aug 31 08:57:55 volumio volumio[1119]: error: WARNING: No clearAddPlayTracks method for service spop Aug 31 08:57:55 volumio volumio[1119]: info: CoreCommandRouter::volumioPrevious Aug 31 08:57:55 volumio volumio[1119]: info: CoreStateMachine::previous Aug 31 08:57:55 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock Aug 31 08:57:55 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock Aug 31 08:57:55 volumio volumio[1119]: info: CoreStateMachine::serviceClearAddPlay Aug 31 08:57:55 volumio volumio[1119]: info: CoreCommandRouter::serviceClearAddPlayTracks Aug 31 08:57:55 volumio volumio[1119]: error: WARNING: No clearAddPlayTracks method for service spop Aug 31 08:57:55 volumio go-librespot[1352]: time="2024-08-31T08:57:55+02:00" level=debug msg="fetched first chunk of 16, total size is 7893924 bytes" Aug 31 08:57:55 volumio go-librespot[1352]: time="2024-08-31T08:57:55+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Aug 31 08:57:55 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 31 08:57:55 volumio go-librespot[1352]: time="2024-08-31T08:57:55+02:00" level=info msg="loaded track \"Santiano\" (uri: spotify:track:48iwF6sUOHYg2foDUPZWUd, paused: false, position: 0ms, duration: 181426ms)" Aug 31 08:57:55 volumio go-librespot[1352]: time="2024-08-31T08:57:55+02:00" level=debug msg="fetched chunk 2/15, size: 524288" Aug 31 08:57:55 volumio go-librespot[1352]: time="2024-08-31T08:57:55+02:00" level=debug msg="fetched chunk 1/15, size: 524288" Aug 31 08:57:55 volumio volumio[1119]: info: CoreCommandRouter::volumioPrevious Aug 31 08:57:55 volumio volumio[1119]: info: CoreStateMachine::previous Aug 31 08:57:55 volumio volumio[1119]: info: CoreStateMachine::updateTrackBlock Aug 31 08:57:55 volumio volumio[1119]: info: CorePlayQueue::getTrackBlock Aug 31 08:57:55 volumio volumio[1119]: info: CoreStateMachine::serviceClearAddPlay Aug 31 08:57:55 volumio volumio[1119]: info: CoreCommandRouter::serviceClearAddPlayTracks Aug 31 08:57:55 volumio volumio[1119]: error: WARNING: No clearAddPlayTracks method for service spop Aug 31 08:57:55 volumio go-librespot[1352]: time="2024-08-31T08:57:55+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:55 volumio go-librespot[1352]: time="2024-08-31T08:57:55+02:00" level=trace msg="emitting websocket event: metadata" Aug 31 08:57:55 volumio volumio[1119]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:48iwF6sUOHYg2foDUPZWUd","name":"Santiano","artist_names":["Santiano"],"album_name":"Bis ans Ende der Welt (Second Edition)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","position":0,"duration":181426,"release_date":"year:2012 month:1 day:1","track_number":1,"disc_number":1}} Aug 31 08:57:55 volumio go-librespot[1352]: time="2024-08-31T08:57:55+02:00" level=debug msg="fetched chunk 3/15, size: 524288" Aug 31 08:57:55 volumio go-librespot[1352]: time="2024-08-31T08:57:55+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 31 08:57:55 volumio go-librespot[1352]: time="2024-08-31T08:57:55+02:00" level=trace msg="emitting websocket event: playing" Aug 31 08:57:55 volumio volumio[1119]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:48iwF6sUOHYg2foDUPZWUd","play_origin":"go-librespot"}} Aug 31 08:57:55 volumio volumio[1119]: SPOTIFY: PUSH STATE SPOTIFY Aug 31 08:57:55 volumio volumio[1119]: SPOTIFY: {"status":"play","service":"spop","title":"Santiano","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","uri":"spotify:track:48iwF6sUOHYg2foDUPZWUd","trackType":"spotify","seek":0,"duration":181,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:55 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Aug 31 08:57:55 volumio volumio[1119]: info: CorePlayQueue::getTrack 10 Aug 31 08:57:55 volumio volumio[1119]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Santiano","artist":"Santiano","album":"Bis ans Ende der Welt (Second Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e02691a46d16f733de33d7d7b0e","uri":"spotify:track:48iwF6sUOHYg2foDUPZWUd","trackType":"spotify","seek":0,"duration":181,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Aug 31 08:57:55 volumio volumio[1119]: verbose: CURRENT POSITION 10 Aug 31 08:57:55 volumio volumio[1119]: info: CoreStateMachine::syncState stateService play Aug 31 08:57:55 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus stop Aug 31 08:57:56 volumio volumio[1119]: info: CoreCommandRouter::volumioPrevious Aug 31 08:57:56 volumio volumio[1119]: info: CoreStateMachine::previous Aug 31 08:57:56 volumio volumio[1119]: info: CorePlayQueue::getTrack 10 Aug 31 08:57:56 volumio volumio[1119]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 08:57:56 volumio volumio[1119]: TypeError: Cannot read property 'service' of undefined Aug 31 08:57:56 volumio volumio[1119]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1315:97) Aug 31 08:57:56 volumio volumio[1119]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:95:28) Aug 31 08:57:56 volumio volumio[1119]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:246:33) Aug 31 08:57:56 volumio volumio[1119]: at Socket.emit (events.js:315:20) Aug 31 08:57:56 volumio volumio[1119]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 31 08:57:56 volumio volumio[1119]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 31 08:57:56 volumio volumio[1119]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 08:57:56 volumio sudo[3191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-31 08:56 Aug 31 08:57:56 volumio sudo[3191]: 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"