Jun 02 22:27:18 volumio go-librespot[12529]: time="2026-06-02T22:27:18+09:00" level=trace msg="emitting websocket event: not_playing" Jun 02 22:27:18 volumio go-librespot[12529]: time="2026-06-02T22:27:18+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:2u6gOYRHuN06LMQDHTwUrl" Jun 02 22:27:18 volumio volumio[12339]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:playlist:7IUIgdUMzpoPQKX2MZrjqt","uri":"spotify:track:5sdQOyqq2IDhvmx2lHOpwd","play_origin":"playlist"}} Jun 02 22:27:18 volumio volumio[12339]: error: Failed to decode event: not_playing Jun 02 22:27:18 volumio go-librespot[12529]: time="2026-06-02T22:27:18+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 02 22:27:18 volumio go-librespot[12529]: time="2026-06-02T22:27:18+09:00" level=trace msg="emitting websocket event: will_play" Jun 02 22:27:18 volumio go-librespot[12529]: time="2026-06-02T22:27:18+09:00" level=info msg="loaded track \"My Romance (take 1)\" (paused: false, position: 0ms, duration: 431213ms, prefetched: true)" uri="spotify:track:2u6gOYRHuN06LMQDHTwUrl" Jun 02 22:27:18 volumio volumio[12339]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:7IUIgdUMzpoPQKX2MZrjqt","uri":"spotify:track:2u6gOYRHuN06LMQDHTwUrl","play_origin":"playlist"}} Jun 02 22:27:19 volumio go-librespot[12529]: time="2026-06-02T22:27:19+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 02 22:27:19 volumio go-librespot[12529]: time="2026-06-02T22:27:19+09:00" level=trace msg="scheduling prefetch in 401s" Jun 02 22:27:19 volumio go-librespot[12529]: time="2026-06-02T22:27:19+09:00" level=trace msg="emitting websocket event: metadata" Jun 02 22:27:19 volumio volumio[12339]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2u6gOYRHuN06LMQDHTwUrl","name":"My Romance (take 1)","artist_names":["Bill Evans Trio"],"album_name":"Waltz For Debby (Original Jazz Classics Remaster 2010)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b97f82fd92c92369eafd9a47","position":0,"duration":431213,"release_date":"year:1961","track_number":4,"disc_number":1}} Jun 02 22:27:19 volumio go-librespot[12529]: time="2026-06-02T22:27:19+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 02 22:27:19 volumio go-librespot[12529]: time="2026-06-02T22:27:19+09:00" level=trace msg="emitting websocket event: playing" Jun 02 22:27:19 volumio volumio[12339]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:7IUIgdUMzpoPQKX2MZrjqt","uri":"spotify:track:2u6gOYRHuN06LMQDHTwUrl","resume":false,"play_origin":"playlist"}} Jun 02 22:27:19 volumio volumio[12339]: SPOTIFY: PUSH STATE SPOTIFY Jun 02 22:27:19 volumio volumio[12339]: SPOTIFY: {"status":"play","service":"spop","title":"My Romance (take 1)","artist":"Bill Evans Trio","album":"Waltz For Debby (Original Jazz Classics Remaster 2010)","albumart":"https://i.scdn.co/image/ab67616d00001e02b97f82fd92c92369eafd9a47","uri":"spotify:track:2u6gOYRHuN06LMQDHTwUrl","trackType":"spotify","seek":0,"duration":431,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 02 22:27:19 volumio volumio[12339]: info: CoreCommandRouter::servicePushState Jun 02 22:27:19 volumio volumio[12339]: info: CoreStateMachine::pushState Jun 02 22:27:19 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 22:27:19 volumio volumio[12339]: info: CoreCommandRouter::volumioPushState Jun 02 22:27:19 volumio volumio[12339]: SPOTIFY: PUSH STATE SPOTIFY Jun 02 22:27:19 volumio volumio[12339]: SPOTIFY: {"status":"play","service":"spop","title":"My Romance (take 1)","artist":"Bill Evans Trio","album":"Waltz For Debby (Original Jazz Classics Remaster 2010)","albumart":"https://i.scdn.co/image/ab67616d00001e02b97f82fd92c92369eafd9a47","uri":"spotify:track:2u6gOYRHuN06LMQDHTwUrl","trackType":"spotify","seek":1000,"duration":431,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 02 22:27:19 volumio volumio[12339]: info: CoreCommandRouter::servicePushState Jun 02 22:27:19 volumio volumio[12339]: info: CoreStateMachine::pushState Jun 02 22:27:19 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 22:27:19 volumio volumio[12339]: info: CoreCommandRouter::volumioPushState Jun 02 22:27:19 volumio go-librespot[12529]: time="2026-06-02T22:27:19+09:00" level=trace msg="received accesspoint ping" Jun 02 22:27:19 volumio go-librespot[12529]: time="2026-06-02T22:27:19+09:00" level=trace msg="received accesspoint pong ack" Jun 02 22:27:20 volumio go-librespot[12529]: time="2026-06-02T22:27:20+09:00" level=trace msg="sent dealer ping" Jun 02 22:27:20 volumio go-librespot[12529]: time="2026-06-02T22:27:20+09:00" level=trace msg="received dealer pong" Jun 02 22:27:29 volumio volumio[12339]: info: CoreCommandRouter::volumioNext Jun 02 22:27:29 volumio volumio[12339]: info: CoreStateMachine::next Jun 02 22:27:29 volumio volumio[12339]: info: Spotify next Jun 02 22:27:29 volumio volumio[12339]: info: Sending Spotify command to local API: /player/next Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4e1aewX6ATPcdfQIqr7gqO" Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=trace msg="emitting websocket event: will_play" Jun 02 22:27:29 volumio volumio[12339]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:7IUIgdUMzpoPQKX2MZrjqt","uri":"spotify:track:4e1aewX6ATPcdfQIqr7gqO","play_origin":"playlist"}} Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=debug msg="selected format OGG_VORBIS_320 (e1a414d9ab467c4a1c91680faa107a4bd4fc9013)" uri="spotify:track:4e1aewX6ATPcdfQIqr7gqO" Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=debug msg="requested aes key for file e1a414d9ab467c4a1c91680faa107a4bd4fc9013, gid: 4e1aewX6ATPcdfQIqr7gqO" Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=trace msg="found 2 cdn urls" uri="spotify:track:4e1aewX6ATPcdfQIqr7gqO" Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=debug msg="fetched first chunk of 13, total size is 6513594 bytes" uri="spotify:track:4e1aewX6ATPcdfQIqr7gqO" Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=info msg="loaded track \"Sticky\" (paused: false, position: 0ms, duration: 157120ms, prefetched: false)" uri="spotify:track:4e1aewX6ATPcdfQIqr7gqO" Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=trace msg="scheduling prefetch in 127s" Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=trace msg="emitting websocket event: metadata" Jun 02 22:27:29 volumio volumio[12339]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4e1aewX6ATPcdfQIqr7gqO","name":"Sticky","artist_names":["KISS OF LIFE"],"album_name":"Sticky","album_cover_url":"https://i.scdn.co/image/ab67616d00001e024cd7f69b85766b3d3035c27c","position":0,"duration":157120,"release_date":"year:2024 month:7 day:1","track_number":1,"disc_number":1}} Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 02 22:27:29 volumio go-librespot[12529]: time="2026-06-02T22:27:29+09:00" level=trace msg="emitting websocket event: playing" Jun 02 22:27:29 volumio volumio[12339]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:7IUIgdUMzpoPQKX2MZrjqt","uri":"spotify:track:4e1aewX6ATPcdfQIqr7gqO","resume":false,"play_origin":"playlist"}} Jun 02 22:27:29 volumio volumio[12339]: SPOTIFY: PUSH STATE SPOTIFY Jun 02 22:27:29 volumio volumio[12339]: SPOTIFY: {"status":"play","service":"spop","title":"Sticky","artist":"KISS OF LIFE","album":"Sticky","albumart":"https://i.scdn.co/image/ab67616d00001e024cd7f69b85766b3d3035c27c","uri":"spotify:track:4e1aewX6ATPcdfQIqr7gqO","trackType":"spotify","seek":0,"duration":157,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 02 22:27:29 volumio volumio[12339]: info: CoreCommandRouter::servicePushState Jun 02 22:27:29 volumio volumio[12339]: info: CoreStateMachine::pushState Jun 02 22:27:29 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 22:27:29 volumio volumio[12339]: info: CoreCommandRouter::volumioPushState Jun 02 22:27:30 volumio go-librespot[12529]: time="2026-06-02T22:27:30+09:00" level=debug msg="fetched chunk 1/12, size: 524288" uri="spotify:track:4e1aewX6ATPcdfQIqr7gqO" Jun 02 22:27:30 volumio volumio[12339]: SPOTIFY: PUSH STATE SPOTIFY Jun 02 22:27:30 volumio volumio[12339]: SPOTIFY: {"status":"play","service":"spop","title":"Sticky","artist":"KISS OF LIFE","album":"Sticky","albumart":"https://i.scdn.co/image/ab67616d00001e024cd7f69b85766b3d3035c27c","uri":"spotify:track:4e1aewX6ATPcdfQIqr7gqO","trackType":"spotify","seek":1000,"duration":157,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 02 22:27:30 volumio volumio[12339]: info: CoreCommandRouter::servicePushState Jun 02 22:27:30 volumio volumio[12339]: info: CoreStateMachine::pushState Jun 02 22:27:30 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 22:27:30 volumio volumio[12339]: info: CoreCommandRouter::volumioPushState Jun 02 22:27:30 volumio go-librespot[12529]: time="2026-06-02T22:27:30+09:00" level=debug msg="fetched chunk 2/12, size: 524288" uri="spotify:track:4e1aewX6ATPcdfQIqr7gqO" Jun 02 22:27:30 volumio go-librespot[12529]: time="2026-06-02T22:27:30+09:00" level=debug msg="fetched chunk 3/12, size: 524288" uri="spotify:track:4e1aewX6ATPcdfQIqr7gqO" Jun 02 22:27:33 volumio volumio[12339]: info: CoreCommandRouter::volumioNext Jun 02 22:27:33 volumio volumio[12339]: info: CoreStateMachine::next Jun 02 22:27:33 volumio volumio[12339]: info: Spotify next Jun 02 22:27:33 volumio volumio[12339]: info: Sending Spotify command to local API: /player/next Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4P5ozkI1bxiGxA5rZ27jlO" Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=trace msg="emitting websocket event: will_play" Jun 02 22:27:33 volumio volumio[12339]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:7IUIgdUMzpoPQKX2MZrjqt","uri":"spotify:track:4P5ozkI1bxiGxA5rZ27jlO","play_origin":"playlist"}} Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=debug msg="selected format OGG_VORBIS_320 (d3b1edf66de2f77cbbadf57a995a664548c0890c)" uri="spotify:track:4P5ozkI1bxiGxA5rZ27jlO" Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=debug msg="requested aes key for file d3b1edf66de2f77cbbadf57a995a664548c0890c, gid: 4P5ozkI1bxiGxA5rZ27jlO" Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=trace msg="found 2 cdn urls" uri="spotify:track:4P5ozkI1bxiGxA5rZ27jlO" Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=debug msg="fetched first chunk of 15, total size is 7809204 bytes" uri="spotify:track:4P5ozkI1bxiGxA5rZ27jlO" Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=info msg="loaded track \"Bubble\" (paused: false, position: 0ms, duration: 179275ms, prefetched: false)" uri="spotify:track:4P5ozkI1bxiGxA5rZ27jlO" Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=trace msg="scheduling prefetch in 149s" Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=trace msg="emitting websocket event: metadata" Jun 02 22:27:33 volumio volumio[12339]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4P5ozkI1bxiGxA5rZ27jlO","name":"Bubble","artist_names":["STAYC"],"album_name":"TEENFRESH","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0205f3e920cfd3ae7fe9192149","position":0,"duration":179275,"release_date":"year:2023 month:8 day:16","track_number":1,"disc_number":1}} Jun 02 22:27:33 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=trace msg="emitting websocket event: playing" Jun 02 22:27:33 volumio volumio[12339]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:7IUIgdUMzpoPQKX2MZrjqt","uri":"spotify:track:4P5ozkI1bxiGxA5rZ27jlO","resume":false,"play_origin":"playlist"}} Jun 02 22:27:33 volumio volumio[12339]: SPOTIFY: PUSH STATE SPOTIFY Jun 02 22:27:33 volumio volumio[12339]: SPOTIFY: {"status":"play","service":"spop","title":"Bubble","artist":"STAYC","album":"TEENFRESH","albumart":"https://i.scdn.co/image/ab67616d00001e0205f3e920cfd3ae7fe9192149","uri":"spotify:track:4P5ozkI1bxiGxA5rZ27jlO","trackType":"spotify","seek":0,"duration":179,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 02 22:27:33 volumio volumio[12339]: info: CoreCommandRouter::servicePushState Jun 02 22:27:33 volumio volumio[12339]: info: CoreStateMachine::pushState Jun 02 22:27:33 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 22:27:33 volumio volumio[12339]: info: CoreCommandRouter::volumioPushState Jun 02 22:27:33 volumio dbus-daemon[569]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.3166' (uid=0 pid=19867 comm="timedatectl show --property=NTPSynchronized --valu") Jun 02 22:27:33 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 02 22:27:33 volumio go-librespot[12529]: time="2026-06-02T22:27:33+09:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:4P5ozkI1bxiGxA5rZ27jlO" Jun 02 22:27:34 volumio volumio[12339]: SPOTIFY: PUSH STATE SPOTIFY Jun 02 22:27:34 volumio volumio[12339]: SPOTIFY: {"status":"play","service":"spop","title":"Bubble","artist":"STAYC","album":"TEENFRESH","albumart":"https://i.scdn.co/image/ab67616d00001e0205f3e920cfd3ae7fe9192149","uri":"spotify:track:4P5ozkI1bxiGxA5rZ27jlO","trackType":"spotify","seek":0,"duration":179,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 02 22:27:34 volumio volumio[12339]: info: CoreCommandRouter::servicePushState Jun 02 22:27:34 volumio volumio[12339]: info: CoreStateMachine::pushState Jun 02 22:27:34 volumio volumio[12339]: info: CoreCommandRouter::volumioPushState Jun 02 22:27:34 volumio go-librespot[12529]: time="2026-06-02T22:27:34+09:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:4P5ozkI1bxiGxA5rZ27jlO" Jun 02 22:27:34 volumio dbus-daemon[569]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 02 22:27:34 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 02 22:27:34 volumio setdatetime-helper.sh[19866]: Time is already synchronized. Jun 02 22:27:34 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 02 22:27:34 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 02 22:27:34 volumio go-librespot[12529]: time="2026-06-02T22:27:34+09:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:4P5ozkI1bxiGxA5rZ27jlO" Jun 02 22:27:39 volumio volumio[12339]: info: CoreCommandRouter::volumioPause Jun 02 22:27:39 volumio volumio[12339]: info: CoreStateMachine::pause Jun 02 22:27:39 volumio volumio[12339]: info: CoreStateMachine::stPlaybackTimer Jun 02 22:27:39 volumio volumio[12339]: info: CoreStateMachine::servicePause Jun 02 22:27:39 volumio volumio[12339]: info: CoreCommandRouter::servicePause Jun 02 22:27:39 volumio volumio[12339]: info: Spotify Received pause Jun 02 22:27:39 volumio volumio[12339]: SPOTIFY: SPOTIFY PAUSE Jun 02 22:27:39 volumio volumio[12339]: SPOTIFY: {"status":"play","title":"Bubble","artist":"STAYC","album":"TEENFRESH","albumart":"https://i.scdn.co/image/ab67616d00001e0205f3e920cfd3ae7fe9192149","uri":"spotify:track:4P5ozkI1bxiGxA5rZ27jlO","trackType":"spotify","codec":"ogg","seek":0,"duration":179,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":0,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Jun 02 22:27:39 volumio volumio[12339]: info: Sending Spotify command to local API: /player/pause Jun 02 22:27:39 volumio go-librespot[12529]: time="2026-06-02T22:27:39+09:00" level=debug msg="pause track at 5414ms" Jun 02 22:27:39 volumio go-librespot[12529]: time="2026-06-02T22:27:39+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 02 22:27:39 volumio go-librespot[12529]: time="2026-06-02T22:27:39+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 02 22:27:39 volumio go-librespot[12529]: time="2026-06-02T22:27:39+09:00" level=trace msg="emitting websocket event: paused" Jun 02 22:27:39 volumio volumio[12339]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:7IUIgdUMzpoPQKX2MZrjqt","uri":"spotify:track:4P5ozkI1bxiGxA5rZ27jlO","play_origin":"playlist"}} Jun 02 22:27:39 volumio volumio[12339]: SPOTIFY: PUSH STATE SPOTIFY Jun 02 22:27:39 volumio volumio[12339]: SPOTIFY: {"status":"pause","service":"spop","title":"Bubble","artist":"STAYC","album":"TEENFRESH","albumart":"https://i.scdn.co/image/ab67616d00001e0205f3e920cfd3ae7fe9192149","uri":"spotify:track:4P5ozkI1bxiGxA5rZ27jlO","trackType":"spotify","seek":6000,"duration":179,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 02 22:27:39 volumio volumio[12339]: info: CoreCommandRouter::servicePushState Jun 02 22:27:39 volumio volumio[12339]: info: CoreStateMachine::pushState Jun 02 22:27:39 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 22:27:39 volumio volumio[12339]: info: CoreCommandRouter::volumioPushState Jun 02 22:27:41 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jun 02 22:27:41 volumio volumio[12339]: info: In handleBrowseUri, curUri=spotify Jun 02 22:27:42 volumio volumio[12339]: info: New access token = BQAOLLwcgOZiSsiEFk7pP3U7u6K2TpGdamWiczjGctfvw66ryDri2DlLxuX8tC1wL45vcP8XVSrUsHUNI5m1_LqdX4Ai9kHRzIwMmL-KccB_ihwZI8NRoCu_Kha3NuGzvbRna7XXqMMAzg_CqB7rPnLeEEUsmXDEAuKg3KSEvMU9oSldOucEgS1jVKERVA5-osucO1KyIuB3EUvFYY4HCJoBFIuM6ab-o0yUR-CToIlkJXI5Y6wtNXKjyLjv6BEVUH5u5RVH7SnfOdkWJpTxLJA5XxU1-K6lriHaFMU0Z2o60bRVG7QHUSnzQc5MeA Jun 02 22:27:42 volumio volumio[12339]: info: New access token = BQDwFZLkZOrP6GnsuK3XILSszTHdQfyiU_4_iyFdJzWTXemBxLPQYDZWiXXg8fcv-jj-ytz72khOBsGQG1uWsZn0bXn3aHtxtmn1IWQZeWFnOTTKD9qgCWiU9M1LQrv0ikxHmKyzg3lcpExgqbjWzxvYMrLmD-JtWHX3cKDBDdHUhzpzmOd9mJrL5_pyPkuLSHWTDQh6LT3hA_vkehmPbnykpFy91zC3KsekcpyABSqatUi6RbDWI35eDl5p7LEm7W-TeD2iH0UA60mjtnjJPSnlDudjtfYG2AI6-smIYUqV4xkJyaPIAU73b8vrhw Jun 02 22:27:42 volumio volumio[12339]: info: New access token = BQCyUZrqIaktJVskHhkQZmgz9ivx1lDmXTixZhb6wfITQF6ErOey_IDpiu4YJikaRTMoHeRlqXRNjQk7e9Rv4feaSYdnLSncz1z7Pjt1AI3ETnM6x5Hc_pvqa_mZLEZ3YcI15jl7ikOoWVy_upC7aVfV6IidxNcb_AsQEKDXabiBp4-J2piTn3kbEpQlcNys2ZpQSVZI2nz8HkwaEPqYQ8fnoJ3l6ZP6D9wmHbxoL34uiH2Tn8uxmrX2235nMSL2FflkwMPYyKFn3VP7eZMABSNZrWzVXjNRnpwEkZctBRdDhCOTzEInJawntABVzg Jun 02 22:27:43 volumio volumio[12339]: info: Preload queue cleared Jun 02 22:27:43 volumio volumio[12339]: info: Preload queue cleared Jun 02 22:27:43 volumio volumio[12339]: info: Preload queue cleared Jun 02 22:27:43 volumio volumio[12339]: info: Preload queue cleared Jun 02 22:27:50 volumio go-librespot[12529]: time="2026-06-02T22:27:50+09:00" level=trace msg="sent dealer ping" Jun 02 22:27:50 volumio go-librespot[12529]: time="2026-06-02T22:27:50+09:00" level=trace msg="received dealer pong" Jun 02 22:27:59 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Jun 02 22:27:59 volumio volumio[12339]: info: [youtube2-browse] browseUri: youtube2 Jun 02 22:27:59 volumio volumio[12339]: info: Preload queue cleared Jun 02 22:27:59 volumio volumio[12339]: info: Preload queue cleared Jun 02 22:28:04 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 02 22:28:08 volumio volumio[12339]: Searching all installed plugins Jun 02 22:28:08 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 22:28:08 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: , search Jun 02 22:28:08 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 02 22:28:08 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 02 22:28:08 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 02 22:28:08 volumio volumio[12339]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 02 22:28:08 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 02 22:28:08 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Jun 02 22:28:08 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: spop , search Jun 02 22:28:09 volumio volumio[12339]: Use XMLElement.setAttribute instead Jun 02 22:28:09 volumio volumio[12339]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 22:28:09 volumio volumio[12339]: TypeError: children[i].attr(...).value is not a function Jun 02 22:28:09 volumio volumio[12339]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jun 02 22:28:09 volumio volumio[12339]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 22:28:09 volumio volumio[12339]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 22:28:10 volumio sudo[19932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-02 22:27' Jun 02 22:28:10 volumio sudo[19932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"