Dec 25 08:28:09 volumio go-librespot[1567]: time="2025-12-25T08:28:09+09:00" level=trace msg="sent dealer ping" Dec 25 08:28:09 volumio go-librespot[1567]: time="2025-12-25T08:28:09+09:00" level=trace msg="received dealer pong" Dec 25 08:28:11 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 25 08:28:11 volumio volumio[1159]: info: In handleBrowseUri, curUri=spotify:artist:6iGrX9E4gniJo9rvUajGUd Dec 25 08:28:12 volumio volumio[1159]: info: Preload queue cleared Dec 25 08:28:12 volumio volumio[1159]: info: Preloading song: spotify:track:4nhbJvsagXbL7xw7dBcKnp Dec 25 08:28:12 volumio volumio[1159]: info: Preloading song: spotify:track:0SkjwskHkZBTSMYXFbTsPF Dec 25 08:28:12 volumio volumio[1159]: info: Preloading song: spotify:track:6Ng82672gGqTeqnXW4zk5y Dec 25 08:28:12 volumio volumio[1159]: info: Preloading song: spotify:track:6IiYXSWh1EjMZ70sW0laYz Dec 25 08:28:12 volumio volumio[1159]: info: Preloading song: spotify:track:2yj5ro1ohYAhvFSwGMTLA6 Dec 25 08:28:12 volumio volumio[1159]: info: Preloading song: spotify:track:2aFvxZ8BUwiMXpw2M0ngPs Dec 25 08:28:12 volumio volumio[1159]: info: Preloading song: spotify:track:3vXcVu7c1ehqWbMFzmtNYx Dec 25 08:28:12 volumio volumio[1159]: info: Preloading song: spotify:track:2bjui8KPEzsBT3JqqnFi1H Dec 25 08:28:12 volumio volumio[1159]: info: Preloading song: spotify:track:5QrRhCJBaxlHTLkNeBuuEz Dec 25 08:28:12 volumio volumio[1159]: info: Preloading song: spotify:track:3Tlm9VPuQo96oTWN9QxZih Dec 25 08:28:12 volumio volumio[1159]: info: Preload queue cleared Dec 25 08:28:12 volumio volumio[1159]: info: Preload queue cleared Dec 25 08:28:21 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 25 08:28:21 volumio volumio[1159]: info: In handleBrowseUri, curUri=spotify:artist:5b1NQwr0WpJwTrTRmHfjZw Dec 25 08:28:22 volumio volumio[1159]: info: Preload queue cleared Dec 25 08:28:22 volumio volumio[1159]: info: Preloading song: spotify:track:68ChNwS0aLEg6TP0SEjaLx Dec 25 08:28:22 volumio volumio[1159]: info: Preloading song: spotify:track:6I0EdmxJfQHKcFsZ2S2nl2 Dec 25 08:28:22 volumio volumio[1159]: info: Preloading song: spotify:track:1HErX3IuLlq5MzHldHcAL6 Dec 25 08:28:22 volumio volumio[1159]: info: Preloading song: spotify:track:3X42iNGY5BCoAF4fJBzcrY Dec 25 08:28:22 volumio volumio[1159]: info: Preloading song: spotify:track:1qe0wdBBEUJoxZN0eD6nne Dec 25 08:28:22 volumio volumio[1159]: info: Preloading song: spotify:track:5G1zmkKey7XBeSF8WZjsTg Dec 25 08:28:22 volumio volumio[1159]: info: Preloading song: spotify:track:4iu8F29oZj3mPx1QdeQypo Dec 25 08:28:22 volumio volumio[1159]: info: Preloading song: spotify:track:1kyVwwMmndDN7ypvvATcIt Dec 25 08:28:22 volumio volumio[1159]: info: Preloading song: spotify:track:3ca3Z9jrL0q1ibTJr7IgXm Dec 25 08:28:22 volumio volumio[1159]: info: Preloading song: spotify:track:0BLsgVNMcQlDezLO0AdsYd Dec 25 08:28:22 volumio volumio[1159]: info: Preload queue cleared Dec 25 08:28:22 volumio volumio[1159]: info: Preload queue cleared Dec 25 08:28:24 volumio volumio[1159]: info: Preload queue cleared Dec 25 08:28:24 volumio volumio[1159]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::ClearQueue Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::stop Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::updateTrackBlock Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::getTrackBlock Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::stPlaybackTimer Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::pushState Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::getTrack 17 Dec 25 08:28:24 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 08:28:24 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::serviceStop Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::getTrack 17 Dec 25 08:28:24 volumio volumio[1159]: info: CoreCommandRouter::serviceStop Dec 25 08:28:24 volumio volumio[1159]: info: Spotify Stop Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: SPOTIFY STOP Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: {"status":"pause","position":17,"title":"Main Title \"Somewhere in My Memory\" (From \"Home Alone\" Soundtrack)","artist":"John Williams","album":"Home Alone (Original Motion Picture Soundtrack)","albumart":"https://i.scdn.co/image/ab67616d0000b273f497846e25233023ca7cb8b1","uri":"spotify:track:1FlxqJmoZWS2q1uRlyZVur","trackType":"spotify","codec":"ogg","seek":214225,"duration":293,"samplerate":"320 kbps","bitdepth":"16 bit","random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":75,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Dec 25 08:28:24 volumio volumio[1159]: info: Sending Spotify command to local API: /player/pause Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::clearPlayQueue Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::saveQueue Dec 25 08:28:24 volumio volumio[1159]: info: CoreCommandRouter::volumioPushQueue Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::addQueueItems Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::addQueueItems Dec 25 08:28:24 volumio volumio[1159]: info: Preload queue cleared Dec 25 08:28:24 volumio volumio[1159]: info: Adding Item to queue: spotify:track:68ChNwS0aLEg6TP0SEjaLx Dec 25 08:28:24 volumio volumio[1159]: info: Exploding uri spotify:track:68ChNwS0aLEg6TP0SEjaLx in service spop Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:68ChNwS0aLEg6TP0SEjaLx Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: SPOTIFY VOLUME 75 Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: VOLUMIO VOLUME 75 Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","service":"spop","name":"Silver Dollar Girl","artist":"The Blue Hearts","album":"Jukebox of Maladies","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c14e0ce078baa52f85c3838","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:28:24 volumio volumio[1159]: info: CoreCommandRouter::volumioPushQueue Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::saveQueue Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::updateTrackBlock Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::getTrackBlock Dec 25 08:28:24 volumio volumio[1159]: info: CoreCommandRouter::volumioPlay Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::play index 0 Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::addQueueItems Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::addQueueItems Dec 25 08:28:24 volumio volumio[1159]: info: Preload queue cleared Dec 25 08:28:24 volumio volumio[1159]: info: Adding Item to queue: spotify:track:6I0EdmxJfQHKcFsZ2S2nl2 Dec 25 08:28:24 volumio volumio[1159]: info: Exploding uri spotify:track:6I0EdmxJfQHKcFsZ2S2nl2 in service spop Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:6I0EdmxJfQHKcFsZ2S2nl2 Dec 25 08:28:24 volumio volumio[1159]: info: Adding Item to queue: spotify:track:1HErX3IuLlq5MzHldHcAL6 Dec 25 08:28:24 volumio volumio[1159]: info: Exploding uri spotify:track:1HErX3IuLlq5MzHldHcAL6 in service spop Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:1HErX3IuLlq5MzHldHcAL6 Dec 25 08:28:24 volumio volumio[1159]: info: Adding Item to queue: spotify:track:3X42iNGY5BCoAF4fJBzcrY Dec 25 08:28:24 volumio volumio[1159]: info: Exploding uri spotify:track:3X42iNGY5BCoAF4fJBzcrY in service spop Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:3X42iNGY5BCoAF4fJBzcrY Dec 25 08:28:24 volumio volumio[1159]: info: Adding Item to queue: spotify:track:1qe0wdBBEUJoxZN0eD6nne Dec 25 08:28:24 volumio volumio[1159]: info: Exploding uri spotify:track:1qe0wdBBEUJoxZN0eD6nne in service spop Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:1qe0wdBBEUJoxZN0eD6nne Dec 25 08:28:24 volumio volumio[1159]: info: Adding Item to queue: spotify:track:5G1zmkKey7XBeSF8WZjsTg Dec 25 08:28:24 volumio volumio[1159]: info: Exploding uri spotify:track:5G1zmkKey7XBeSF8WZjsTg in service spop Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:5G1zmkKey7XBeSF8WZjsTg Dec 25 08:28:24 volumio volumio[1159]: info: Adding Item to queue: spotify:track:4iu8F29oZj3mPx1QdeQypo Dec 25 08:28:24 volumio volumio[1159]: info: Exploding uri spotify:track:4iu8F29oZj3mPx1QdeQypo in service spop Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:4iu8F29oZj3mPx1QdeQypo Dec 25 08:28:24 volumio volumio[1159]: info: Adding Item to queue: spotify:track:1kyVwwMmndDN7ypvvATcIt Dec 25 08:28:24 volumio volumio[1159]: info: Exploding uri spotify:track:1kyVwwMmndDN7ypvvATcIt in service spop Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:1kyVwwMmndDN7ypvvATcIt Dec 25 08:28:24 volumio volumio[1159]: info: Adding Item to queue: spotify:track:3ca3Z9jrL0q1ibTJr7IgXm Dec 25 08:28:24 volumio volumio[1159]: info: Exploding uri spotify:track:3ca3Z9jrL0q1ibTJr7IgXm in service spop Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:3ca3Z9jrL0q1ibTJr7IgXm Dec 25 08:28:24 volumio volumio[1159]: info: Adding Item to queue: spotify:track:0BLsgVNMcQlDezLO0AdsYd Dec 25 08:28:24 volumio volumio[1159]: info: Exploding uri spotify:track:0BLsgVNMcQlDezLO0AdsYd in service spop Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:0BLsgVNMcQlDezLO0AdsYd Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::stop Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::play index undefined Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::startPlaybackTimer Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Dec 25 08:28:24 volumio volumio[1159]: info: [1766618904723] ControllerSpotify::clearAddPlayTrack Dec 25 08:28:24 volumio volumio[1159]: info: Sending Spotify command with payload to local API: /player/play Dec 25 08:28:24 volumio go-librespot[1567]: time="2025-12-25T08:28:24+09:00" level=debug msg="renewing login5 access token" Dec 25 08:28:24 volumio go-librespot[1567]: time="2025-12-25T08:28:24+09:00" level=info msg="authenticated Login5" username="lh*********************m0" Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1HErX3IuLlq5MzHldHcAL6","service":"spop","name":"Girl For the End of the World","artist":"The Blue Hearts","album":"Jukebox of Maladies","type":"song","duration":276,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c14e0ce078baa52f85c3838","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6I0EdmxJfQHKcFsZ2S2nl2","service":"spop","name":"Velvet Prison","artist":"The Blue Hearts","album":"Jukebox of Maladies","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c14e0ce078baa52f85c3838","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3X42iNGY5BCoAF4fJBzcrY","service":"spop","name":"You Shine Down","artist":"The Blue Hearts","album":"Jukebox of Maladies","type":"song","duration":312,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c14e0ce078baa52f85c3838","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1qe0wdBBEUJoxZN0eD6nne","service":"spop","name":"Jukebox of Maladies","artist":"The Blue Hearts","album":"Jukebox of Maladies","type":"song","duration":273,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c14e0ce078baa52f85c3838","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5G1zmkKey7XBeSF8WZjsTg","service":"spop","name":"Sorrow","artist":"The Blue Hearts","album":"Sorrow","type":"song","duration":232,"albumart":"https://i.scdn.co/image/ab67616d0000b273aac455b04bff0a659f783069","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4iu8F29oZj3mPx1QdeQypo","service":"spop","name":"The One Who Loves","artist":"The Blue Hearts","album":"Jukebox of Maladies","type":"song","duration":257,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c14e0ce078baa52f85c3838","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1kyVwwMmndDN7ypvvATcIt","service":"spop","name":"Snake Oil Sam","artist":"The Blue Hearts","album":"Jukebox of Maladies","type":"song","duration":227,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c14e0ce078baa52f85c3838","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3ca3Z9jrL0q1ibTJr7IgXm","service":"spop","name":"A Thousand Stars (Guitar Mix)","artist":"The Blue Hearts","album":"Blue Country","type":"song","duration":179,"albumart":"https://i.scdn.co/image/ab67616d0000b273205694a672949c77219743a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:28:24 volumio go-librespot[1567]: time="2025-12-25T08:28:24+09:00" level=debug msg="resolved context of track" uri="spotify:track:68ChNwS0aLEg6TP0SEjaLx" Dec 25 08:28:24 volumio go-librespot[1567]: time="2025-12-25T08:28:24+09:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:68ChNwS0aLEg6TP0SEjaLx" Dec 25 08:28:24 volumio go-librespot[1567]: time="2025-12-25T08:28:24+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:68ChNwS0aLEg6TP0SEjaLx" Dec 25 08:28:24 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0BLsgVNMcQlDezLO0AdsYd","service":"spop","name":"Delirious","artist":"The Blue Hearts","album":"Blue Country","type":"song","duration":206,"albumart":"https://i.scdn.co/image/ab67616d0000b273205694a672949c77219743a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:28:24 volumio volumio[1159]: info: CoreCommandRouter::volumioPushQueue Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::saveQueue Dec 25 08:28:24 volumio volumio[1159]: info: CoreStateMachine::updateTrackBlock Dec 25 08:28:24 volumio volumio[1159]: info: CorePlayQueue::getTrackBlock Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=trace msg="emitting websocket event: will_play" Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","play_origin":"go-librespot"}} Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="selected format OGG_VORBIS_320 (0860edf0c76e7fee18e7d7b8b5afc88284645d1d)" uri="spotify:track:68ChNwS0aLEg6TP0SEjaLx" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="requested aes key for file 0860edf0c76e7fee18e7d7b8b5afc88284645d1d, gid: 68ChNwS0aLEg6TP0SEjaLx" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1400" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=trace msg="found 2 cdn urls" uri="spotify:track:68ChNwS0aLEg6TP0SEjaLx" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="fetched first chunk of 18, total size is 9314396 bytes" uri="spotify:track:68ChNwS0aLEg6TP0SEjaLx" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="created new output device" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1144" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames, PCM format = FLOAT_LE" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=info msg="loaded track \"Silver Dollar Girl\" (paused: false, position: 0ms, duration: 219320ms, prefetched: false)" uri="spotify:track:68ChNwS0aLEg6TP0SEjaLx" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:68ChNwS0aLEg6TP0SEjaLx" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=trace msg="scheduling prefetch in 189s" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=trace msg="emitting websocket event: metadata" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:68ChNwS0aLEg6TP0SEjaLx" Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","name":"Silver Dollar Girl","artist_names":["The Blue Hearts"],"album_name":"Jukebox of Maladies","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028c14e0ce078baa52f85c3838","position":0,"duration":219320,"release_date":"year:2010 month:1 day:11","track_number":1,"disc_number":1}} Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:68ChNwS0aLEg6TP0SEjaLx" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 25 08:28:25 volumio go-librespot[1567]: time="2025-12-25T08:28:25+09:00" level=trace msg="emitting websocket event: playing" Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","resume":false,"play_origin":"go-librespot"}} Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: PUSH STATE SPOTIFY Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: {"status":"play","service":"spop","title":"Silver Dollar Girl","artist":"The Blue Hearts","album":"Jukebox of Maladies","albumart":"https://i.scdn.co/image/ab67616d00001e028c14e0ce078baa52f85c3838","uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","trackType":"spotify","seek":0,"duration":219,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 25 08:28:25 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Dec 25 08:28:25 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Dec 25 08:28:25 volumio volumio[1159]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Silver Dollar Girl","artist":"The Blue Hearts","album":"Jukebox of Maladies","albumart":"https://i.scdn.co/image/ab67616d00001e028c14e0ce078baa52f85c3838","uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","trackType":"spotify","seek":0,"duration":219,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 25 08:28:25 volumio volumio[1159]: verbose: CURRENT POSITION 0 Dec 25 08:28:25 volumio volumio[1159]: info: CoreStateMachine::syncState stateService play Dec 25 08:28:25 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: PUSH STATE SPOTIFY Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: {"status":"play","service":"spop","title":"Silver Dollar Girl","artist":"The Blue Hearts","album":"Jukebox of Maladies","albumart":"https://i.scdn.co/image/ab67616d00001e028c14e0ce078baa52f85c3838","uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","trackType":"spotify","seek":0,"duration":219,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 25 08:28:25 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Dec 25 08:28:25 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Dec 25 08:28:25 volumio volumio[1159]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Silver Dollar Girl","artist":"The Blue Hearts","album":"Jukebox of Maladies","albumart":"https://i.scdn.co/image/ab67616d00001e028c14e0ce078baa52f85c3838","uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","trackType":"spotify","seek":0,"duration":219,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 25 08:28:25 volumio volumio[1159]: verbose: CURRENT POSITION 0 Dec 25 08:28:25 volumio volumio[1159]: info: CoreStateMachine::syncState stateService play Dec 25 08:28:25 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus play Dec 25 08:28:25 volumio volumio[1159]: info: Received an update from plugin. extracting info from payload Dec 25 08:28:25 volumio volumio[1159]: info: CoreStateMachine::pushState Dec 25 08:28:25 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Dec 25 08:28:25 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 08:28:25 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Dec 25 08:28:25 volumio volumio[1159]: info: CoreStateMachine::pushState Dec 25 08:28:25 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Dec 25 08:28:25 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: SPOTIFY VOLUME 75 Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: VOLUMIO VOLUME 75 Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: SPOTIFY VOLUME 75 Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: VOLUMIO VOLUME 75 Dec 25 08:28:25 volumio volumio[1159]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 25 08:28:38 volumio go-librespot[1567]: time="2025-12-25T08:28:38+09:00" level=debug msg="fetched chunk 4/17, size: 524288" uri="spotify:track:68ChNwS0aLEg6TP0SEjaLx" Dec 25 08:28:39 volumio go-librespot[1567]: time="2025-12-25T08:28:39+09:00" level=trace msg="sent dealer ping" Dec 25 08:28:39 volumio go-librespot[1567]: time="2025-12-25T08:28:39+09:00" level=trace msg="received dealer pong" Dec 25 08:28:39 volumio volumio[1159]: info: CoreCommandRouter::volumioPause Dec 25 08:28:39 volumio volumio[1159]: info: CoreStateMachine::pause Dec 25 08:28:39 volumio volumio[1159]: info: CoreStateMachine::stPlaybackTimer Dec 25 08:28:39 volumio volumio[1159]: info: CoreStateMachine::servicePause Dec 25 08:28:39 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Dec 25 08:28:39 volumio volumio[1159]: info: CoreCommandRouter::servicePause Dec 25 08:28:39 volumio volumio[1159]: info: Spotify Received pause Dec 25 08:28:39 volumio volumio[1159]: SPOTIFY: SPOTIFY PAUSE Dec 25 08:28:39 volumio volumio[1159]: SPOTIFY: {"status":"play","position":0,"title":"Silver Dollar Girl","artist":"The Blue Hearts","album":"Jukebox of Maladies","albumart":"https://i.scdn.co/image/ab67616d0000b2738c14e0ce078baa52f85c3838","uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","trackType":"spotify","codec":"ogg","seek":0,"duration":219,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":75,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Dec 25 08:28:39 volumio volumio[1159]: info: Sending Spotify command to local API: /player/pause Dec 25 08:28:39 volumio go-librespot[1567]: time="2025-12-25T08:28:39+09:00" level=debug msg="pause track at 14106ms" Dec 25 08:28:39 volumio go-librespot[1567]: time="2025-12-25T08:28:39+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 25 08:28:39 volumio go-librespot[1567]: time="2025-12-25T08:28:39+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 25 08:28:39 volumio go-librespot[1567]: time="2025-12-25T08:28:39+09:00" level=trace msg="emitting websocket event: paused" Dec 25 08:28:39 volumio volumio[1159]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","play_origin":"go-librespot"}} Dec 25 08:28:39 volumio volumio[1159]: SPOTIFY: PUSH STATE SPOTIFY Dec 25 08:28:39 volumio volumio[1159]: SPOTIFY: {"status":"pause","service":"spop","title":"Silver Dollar Girl","artist":"The Blue Hearts","album":"Jukebox of Maladies","albumart":"https://i.scdn.co/image/ab67616d00001e028c14e0ce078baa52f85c3838","uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","trackType":"spotify","seek":14000,"duration":219,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 25 08:28:39 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Dec 25 08:28:39 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Dec 25 08:28:39 volumio volumio[1159]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Silver Dollar Girl","artist":"The Blue Hearts","album":"Jukebox of Maladies","albumart":"https://i.scdn.co/image/ab67616d00001e028c14e0ce078baa52f85c3838","uri":"spotify:track:68ChNwS0aLEg6TP0SEjaLx","trackType":"spotify","seek":14000,"duration":219,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 25 08:28:39 volumio volumio[1159]: verbose: CURRENT POSITION 0 Dec 25 08:28:39 volumio volumio[1159]: info: CoreStateMachine::syncState stateService pause Dec 25 08:28:39 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus pause Dec 25 08:28:39 volumio volumio[1159]: info: CoreStateMachine::pushState Dec 25 08:28:39 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Dec 25 08:28:39 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 08:28:39 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Dec 25 08:28:39 volumio volumio[1159]: info: CoreStateMachine::stPlaybackTimer Dec 25 08:28:39 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 25 08:28:39 volumio volumio[1159]: SPOTIFY: SPOTIFY VOLUME 75 Dec 25 08:28:39 volumio volumio[1159]: SPOTIFY: VOLUMIO VOLUME 75 Dec 25 08:28:39 volumio volumio[1159]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 25 08:29:03 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 25 08:29:03 volumio volumio[1159]: info: In handleBrowseUri, curUri=spotify:album:6R43Eyv2jTYbpovBJnUbpV Dec 25 08:29:03 volumio volumio[1159]: info: Preload queue cleared Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:4ZwhPsek18P2RZ1FCKrWKA Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:0c5dMTzTTEGsL9ysdGEV6M Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:7k12I7oGwXqfVI9U3CoOm6 Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:5vNCpL2mJ15HOdKY3aqpd5 Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:5vHfCT9P8KVD3gBqrBhFBP Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:0mQVi1RPN9A2DmLhMrBXTp Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:6qkWC6ygLqkwAfZd0WbWmJ Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:61KfqGe6322zMPNaXKOC81 Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:5BrPzdv55AcN8lIAjvTH8A Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:7oonNVseai2luQajVgdVT8 Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:79e1cnt4cCAnoEkAx9c1ba Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:5eJQuWbDbSrDr3Y8XxZIAF Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:5m2VsTushhsTp9uHmgghHa Dec 25 08:29:03 volumio volumio[1159]: info: Preloading song: spotify:track:0xYvZWWUOKiYw1qZ3KIQx4 Dec 25 08:29:03 volumio volumio[1159]: info: Exploding uri spotify:track:4ZwhPsek18P2RZ1FCKrWKA in service spop Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:4ZwhPsek18P2RZ1FCKrWKA Dec 25 08:29:03 volumio volumio[1159]: info: Exploding uri spotify:track:0c5dMTzTTEGsL9ysdGEV6M in service spop Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:0c5dMTzTTEGsL9ysdGEV6M Dec 25 08:29:03 volumio volumio[1159]: info: Exploding uri spotify:track:7k12I7oGwXqfVI9U3CoOm6 in service spop Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:7k12I7oGwXqfVI9U3CoOm6 Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4ZwhPsek18P2RZ1FCKrWKA","service":"spop","name":"人にやさしく","artist":"NG HEAD","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:03 volumio volumio[1159]: info: Exploding uri spotify:track:5vNCpL2mJ15HOdKY3aqpd5 in service spop Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:5vNCpL2mJ15HOdKY3aqpd5 Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0c5dMTzTTEGsL9ysdGEV6M","service":"spop","name":"リンダ リンダ","artist":"Sunsqritt","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":201,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:03 volumio volumio[1159]: info: Exploding uri spotify:track:5vHfCT9P8KVD3gBqrBhFBP in service spop Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:5vHfCT9P8KVD3gBqrBhFBP Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7k12I7oGwXqfVI9U3CoOm6","service":"spop","name":"ダンス・ナンバー","artist":"RYO the SKYWALKER","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":86,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:03 volumio volumio[1159]: info: Exploding uri spotify:track:0mQVi1RPN9A2DmLhMrBXTp in service spop Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:0mQVi1RPN9A2DmLhMrBXTp Dec 25 08:29:03 volumio volumio[1159]: info: Exploding uri spotify:track:6qkWC6ygLqkwAfZd0WbWmJ in service spop Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:6qkWC6ygLqkwAfZd0WbWmJ Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5vNCpL2mJ15HOdKY3aqpd5","service":"spop","name":"少年の詩","artist":"Mison-B","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":230,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5vHfCT9P8KVD3gBqrBhFBP","service":"spop","name":"チェインギャング","artist":"Domino-Kat","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":269,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:03 volumio volumio[1159]: info: Exploding uri spotify:track:61KfqGe6322zMPNaXKOC81 in service spop Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:61KfqGe6322zMPNaXKOC81 Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0mQVi1RPN9A2DmLhMrBXTp","service":"spop","name":"TRAIN-TRAIN","artist":"Eelman","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":235,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:03 volumio volumio[1159]: info: Exploding uri spotify:track:5BrPzdv55AcN8lIAjvTH8A in service spop Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:5BrPzdv55AcN8lIAjvTH8A Dec 25 08:29:03 volumio volumio[1159]: info: Exploding uri spotify:track:7oonNVseai2luQajVgdVT8 in service spop Dec 25 08:29:03 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:7oonNVseai2luQajVgdVT8 Dec 25 08:29:04 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:61KfqGe6322zMPNaXKOC81","service":"spop","name":"緑のハッパ","artist":"笑連隊","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:04 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6qkWC6ygLqkwAfZd0WbWmJ","service":"spop","name":"ラブレター (Acoustic Ver.)","artist":"Rickie-G","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:04 volumio volumio[1159]: info: Exploding uri spotify:track:79e1cnt4cCAnoEkAx9c1ba in service spop Dec 25 08:29:04 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:79e1cnt4cCAnoEkAx9c1ba Dec 25 08:29:04 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5BrPzdv55AcN8lIAjvTH8A","service":"spop","name":"青空","artist":"TOMO","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":225,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:04 volumio volumio[1159]: info: Exploding uri spotify:track:5eJQuWbDbSrDr3Y8XxZIAF in service spop Dec 25 08:29:04 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:5eJQuWbDbSrDr3Y8XxZIAF Dec 25 08:29:04 volumio volumio[1159]: info: Exploding uri spotify:track:5m2VsTushhsTp9uHmgghHa in service spop Dec 25 08:29:04 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:5m2VsTushhsTp9uHmgghHa Dec 25 08:29:04 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7oonNVseai2luQajVgdVT8","service":"spop","name":"THE ROLLING MAN","artist":"Rudebwoy Face","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":302,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:04 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:79e1cnt4cCAnoEkAx9c1ba","service":"spop","name":"TOO MUCH PAIN","artist":"Peter Man","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":375,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:04 volumio volumio[1159]: info: Exploding uri spotify:track:0xYvZWWUOKiYw1qZ3KIQx4 in service spop Dec 25 08:29:04 volumio volumio[1159]: SPOTIFY: EXPLODING URI:spotify:track:0xYvZWWUOKiYw1qZ3KIQx4 Dec 25 08:29:04 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5eJQuWbDbSrDr3Y8XxZIAF","service":"spop","name":"月の爆撃機","artist":"RUEED","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":193,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:04 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5m2VsTushhsTp9uHmgghHa","service":"spop","name":"1000のバイオリン","artist":"CHEHON","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":203,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:04 volumio volumio[1159]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0xYvZWWUOKiYw1qZ3KIQx4","service":"spop","name":"情熱の薔薇","artist":"三宅 洋平","album":"RESPECT!!! THE BLUE HEARTS -A Reggae Tribute to THE BLUE HEARTS-","type":"song","duration":269,"albumart":"https://i.scdn.co/image/ab67616d0000b273b263d6f3c4d0740658694654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 25 08:29:08 volumio go-librespot[1567]: time="2025-12-25T08:29:08+09:00" level=trace msg="received accesspoint ping" Dec 25 08:29:08 volumio go-librespot[1567]: time="2025-12-25T08:29:08+09:00" level=trace msg="received accesspoint pong ack" Dec 25 08:29:09 volumio go-librespot[1567]: time="2025-12-25T08:29:09+09:00" level=trace msg="sent dealer ping" Dec 25 08:29:09 volumio go-librespot[1567]: time="2025-12-25T08:29:09+09:00" level=trace msg="received dealer pong" Dec 25 08:29:20 volumio volumio[1159]: Searching all installed plugins Dec 25 08:29:20 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 08:29:20 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: , search Dec 25 08:29:20 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 25 08:29:20 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 25 08:29:20 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 25 08:29:20 volumio volumio[1159]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 25 08:29:20 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 25 08:29:20 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 25 08:29:21 volumio volumio[1159]: info: SHOUTCASTのクエリ中にエラーが発生しました Dec 25 08:29:21 volumio volumio[1159]: info: All search sources collected, pushing search results Dec 25 08:29:23 volumio volumio[1159]: Searching all installed plugins Dec 25 08:29:23 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 08:29:23 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: , search Dec 25 08:29:23 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 25 08:29:23 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 25 08:29:23 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 25 08:29:23 volumio volumio[1159]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 25 08:29:23 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 25 08:29:23 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 25 08:29:23 volumio volumio[1159]: info: All search sources collected, pushing search results Dec 25 08:29:39 volumio go-librespot[1567]: time="2025-12-25T08:29:39+09:00" level=trace msg="sent dealer ping" Dec 25 08:29:39 volumio go-librespot[1567]: time="2025-12-25T08:29:39+09:00" level=trace msg="received dealer pong" Dec 25 08:29:42 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 25 08:29:42 volumio volumio[1159]: info: In handleBrowseUri, curUri=spotify:artist:2zznlBwNMFL1nlABMoqq0b Dec 25 08:29:42 volumio volumio[1159]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 08:29:42 volumio volumio[1159]: TypeError: Cannot read properties of undefined (reading 'url') Dec 25 08:29:42 volumio volumio[1159]: at /data/plugins/music_service/spop/index.js:2449:60 Dec 25 08:29:42 volumio volumio[1159]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 25 08:29:42 volumio volumio[1159]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 08:29:43 volumio sudo[7224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-25 08:28' Dec 25 08:29:43 volumio sudo[7224]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"