-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Mon 2025-05-05 00:52:12 +07. -- May 05 00:51:03 volumio go-librespot[9821]: time="2025-05-05T00:51:03+07:00" level=debug msg="fetched chunk 9/16, size: 524288" uri="spotify:track:7yWEPokjQeePPTZD9RqxP4" May 05 00:51:11 volumio go-librespot[9821]: time="2025-05-05T00:51:11+07:00" level=trace msg="received accesspoint ping" May 05 00:51:11 volumio go-librespot[9821]: time="2025-05-05T00:51:11+07:00" level=trace msg="received accesspoint pong ack" May 05 00:51:11 volumio go-librespot[9821]: time="2025-05-05T00:51:11+07:00" level=trace msg="sent dealer ping" May 05 00:51:11 volumio go-librespot[9821]: time="2025-05-05T00:51:11+07:00" level=trace msg="received dealer pong" May 05 00:51:12 volumio volumio[998]: info: CoreCommandRouter::volumioGetState May 05 00:51:18 volumio go-librespot[9821]: time="2025-05-05T00:51:18+07:00" level=debug msg="fetched chunk 10/16, size: 524288" uri="spotify:track:7yWEPokjQeePPTZD9RqxP4" May 05 00:51:32 volumio go-librespot[9821]: time="2025-05-05T00:51:32+07:00" level=debug msg="fetched chunk 11/16, size: 524288" uri="spotify:track:7yWEPokjQeePPTZD9RqxP4" May 05 00:51:39 volumio volumio[998]: info: CoreCommandRouter::volumioGetQueue May 05 00:51:39 volumio volumio[998]: info: CoreStateMachine::getQueue May 05 00:51:39 volumio volumio[998]: info: CorePlayQueue::getQueue May 05 00:51:41 volumio go-librespot[9821]: time="2025-05-05T00:51:41+07:00" level=trace msg="sent dealer ping" May 05 00:51:41 volumio go-librespot[9821]: time="2025-05-05T00:51:41+07:00" level=trace msg="received dealer pong" May 05 00:51:43 volumio volumio[998]: info: CoreCommandRouter::volumioPause May 05 00:51:43 volumio volumio[998]: info: CoreStateMachine::pause May 05 00:51:43 volumio volumio[998]: info: CoreStateMachine::stPlaybackTimer May 05 00:51:43 volumio volumio[998]: info: CoreStateMachine::servicePause May 05 00:51:43 volumio volumio[998]: info: CoreCommandRouter::servicePause May 05 00:51:43 volumio volumio[998]: info: Spotify Received pause May 05 00:51:43 volumio volumio[998]: SPOTIFY: SPOTIFY PAUSE May 05 00:51:43 volumio volumio[998]: SPOTIFY: {"status":"play","title":"All About That Bass - 2015 European Cast","artist":"Scott Bradlee's Postmodern Jukebox","album":"Emoji Antique","albumart":"https://i.scdn.co/image/ab67616d00001e02f29a2f32a138c0158aea2150","uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","trackType":"spotify","codec":"ogg","seek":79356,"duration":241,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":95,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} May 05 00:51:43 volumio volumio[998]: info: Sending Spotify command to local API: /player/pause May 05 00:51:43 volumio go-librespot[9821]: time="2025-05-05T00:51:43+07:00" level=debug msg="pause track at 128156ms" May 05 00:51:43 volumio go-librespot[9821]: time="2025-05-05T00:51:43+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 00:51:43 volumio go-librespot[9821]: time="2025-05-05T00:51:43+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 00:51:43 volumio go-librespot[9821]: time="2025-05-05T00:51:43+07:00" level=trace msg="emitting websocket event: paused" May 05 00:51:43 volumio volumio[998]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","play_origin":"playlist"}} May 05 00:51:43 volumio volumio[998]: SPOTIFY: PUSH STATE SPOTIFY May 05 00:51:43 volumio volumio[998]: SPOTIFY: {"status":"pause","service":"spop","title":"All About That Bass - 2015 European Cast","artist":"Scott Bradlee's Postmodern Jukebox","album":"Emoji Antique","albumart":"https://i.scdn.co/image/ab67616d00001e02f29a2f32a138c0158aea2150","uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","trackType":"spotify","seek":127356,"duration":241,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 05 00:51:43 volumio volumio[998]: info: CoreCommandRouter::servicePushState May 05 00:51:43 volumio volumio[998]: info: CoreStateMachine::pushState May 05 00:51:43 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 00:51:43 volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 05 00:51:43 volumio volumio[998]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","play_origin":"playlist"}} May 05 00:51:43 volumio volumio[998]: SPOTIFY: PUSH STATE SPOTIFY May 05 00:51:43 volumio volumio[998]: SPOTIFY: {"status":"pause","service":"spop","title":"All About That Bass - 2015 European Cast","artist":"Scott Bradlee's Postmodern Jukebox","album":"Emoji Antique","albumart":"https://i.scdn.co/image/ab67616d00001e02f29a2f32a138c0158aea2150","uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","trackType":"spotify","seek":127356,"duration":241,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 05 00:51:43 volumio volumio[998]: info: CoreCommandRouter::servicePushState May 05 00:51:43 volumio volumio[998]: info: CoreStateMachine::pushState May 05 00:51:43 volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 05 00:51:43 volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 05 00:51:43 volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 05 00:51:43 volumio volumio[998]: info: touch_display: Setting screensaver timeout to 0 seconds. May 05 00:51:48 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 05 00:51:48 volumio volumio[998]: info: In handleBrowseUri, curUri=spotify May 05 00:51:48 volumio volumio[998]: info: Preload queue cleared May 05 00:51:48 volumio volumio[998]: info: Preload queue cleared May 05 00:51:48 volumio volumio[998]: info: Preload queue cleared May 05 00:51:52 volumio volumio[998]: info: Preload queue cleared May 05 00:51:52 volumio volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems May 05 00:51:52 volumio volumio[998]: info: CoreStateMachine::ClearQueue May 05 00:51:52 volumio volumio[998]: info: CoreStateMachine::stop May 05 00:51:52 volumio volumio[998]: info: CoreStateMachine::serviceStop May 05 00:51:52 volumio volumio[998]: info: CoreCommandRouter::serviceStop May 05 00:51:52 volumio volumio[998]: info: Spotify Stop May 05 00:51:52 volumio volumio[998]: SPOTIFY: SPOTIFY STOP May 05 00:51:52 volumio volumio[998]: SPOTIFY: {"status":"pause","title":"All About That Bass - 2015 European Cast","artist":"Scott Bradlee's Postmodern Jukebox","album":"Emoji Antique","albumart":"https://i.scdn.co/image/ab67616d00001e02f29a2f32a138c0158aea2150","uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","trackType":"spotify","codec":"ogg","seek":127356,"duration":241,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":95,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} May 05 00:51:52 volumio volumio[998]: info: Sending Spotify command to local API: /player/pause May 05 00:51:52 volumio volumio[998]: info: CorePlayQueue::clearPlayQueue May 05 00:51:52 volumio volumio[998]: info: CorePlayQueue::saveQueue May 05 00:51:52 volumio volumio[998]: info: CoreCommandRouter::volumioPushQueue May 05 00:51:52 volumio volumio[998]: info: CoreStateMachine::addQueueItems May 05 00:51:52 volumio volumio[998]: info: CorePlayQueue::addQueueItems May 05 00:51:52 volumio volumio[998]: info: Preload queue cleared May 05 00:51:52 volumio volumio[998]: info: Adding Item to queue: spotify:playlist:37i9dQZF1DWW1S2VXZ4bIj May 05 00:51:52 volumio volumio[998]: info: Exploding uri spotify:playlist:37i9dQZF1DWW1S2VXZ4bIj in service spop May 05 00:51:52 volumio volumio[998]: SPOTIFY: EXPLODING URI:spotify:playlist:37i9dQZF1DWW1S2VXZ4bIj May 05 00:51:52 volumio go-librespot[9821]: time="2025-05-05T00:51:52+07:00" level=debug msg="pause track at 128580ms" May 05 00:51:52 volumio go-librespot[9821]: time="2025-05-05T00:51:52+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 00:51:52 volumio go-librespot[9821]: time="2025-05-05T00:51:52+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 00:51:52 volumio go-librespot[9821]: time="2025-05-05T00:51:52+07:00" level=trace msg="emitting websocket event: paused" May 05 00:51:52 volumio volumio[998]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","play_origin":"playlist"}} May 05 00:51:52 volumio volumio[998]: SPOTIFY: PUSH STATE SPOTIFY May 05 00:51:52 volumio volumio[998]: SPOTIFY: {"status":"pause","service":"spop","title":"All About That Bass - 2015 European Cast","artist":"Scott Bradlee's Postmodern Jukebox","album":"Emoji Antique","albumart":"https://i.scdn.co/image/ab67616d00001e02f29a2f32a138c0158aea2150","uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","trackType":"spotify","seek":127356,"duration":241,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 05 00:51:52 volumio volumio[998]: info: CoreCommandRouter::servicePushState May 05 00:51:52 volumio volumio[998]: info: CoreStateMachine::pushState May 05 00:51:52 volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 05 00:51:52 volumio volumio[998]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","play_origin":"playlist"}} May 05 00:51:52 volumio volumio[998]: SPOTIFY: PUSH STATE SPOTIFY May 05 00:51:52 volumio volumio[998]: SPOTIFY: {"status":"pause","service":"spop","title":"All About That Bass - 2015 European Cast","artist":"Scott Bradlee's Postmodern Jukebox","album":"Emoji Antique","albumart":"https://i.scdn.co/image/ab67616d00001e02f29a2f32a138c0158aea2150","uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","trackType":"spotify","seek":127356,"duration":241,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 05 00:51:52 volumio volumio[998]: info: CoreCommandRouter::servicePushState May 05 00:51:52 volumio volumio[998]: info: CoreStateMachine::pushState May 05 00:51:52 volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 05 00:51:52 volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 05 00:51:52 volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 05 00:51:53 volumio volumio[998]: info: CoreCommandRouter::volumioPushQueue May 05 00:51:53 volumio volumio[998]: info: CorePlayQueue::saveQueue May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::updateTrackBlock May 05 00:51:53 volumio volumio[998]: info: CorePlayQueue::getTrackBlock May 05 00:51:53 volumio volumio[998]: info: CoreCommandRouter::volumioPlay May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::play index 0 May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::stop May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::updateTrackBlock May 05 00:51:53 volumio volumio[998]: info: CorePlayQueue::getTrackBlock May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::stPlaybackTimer May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::pushState May 05 00:51:53 volumio volumio[998]: info: CorePlayQueue::getTrack 0 May 05 00:51:53 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 00:51:53 volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::serviceStop May 05 00:51:53 volumio volumio[998]: info: CorePlayQueue::getTrack 0 May 05 00:51:53 volumio volumio[998]: info: CoreCommandRouter::serviceStop May 05 00:51:53 volumio volumio[998]: info: Spotify Stop May 05 00:51:53 volumio volumio[998]: SPOTIFY: SPOTIFY STOP May 05 00:51:53 volumio volumio[998]: SPOTIFY: {"status":"pause","title":"All About That Bass - 2015 European Cast","artist":"Scott Bradlee's Postmodern Jukebox","album":"Emoji Antique","albumart":"https://i.scdn.co/image/ab67616d00001e02f29a2f32a138c0158aea2150","uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","trackType":"spotify","codec":"ogg","seek":127356,"duration":241,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":95,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} May 05 00:51:53 volumio volumio[998]: info: Sending Spotify command to local API: /player/pause May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::play index undefined May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 00:51:53 volumio volumio[998]: info: CorePlayQueue::getTrack 0 May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::startPlaybackTimer May 05 00:51:53 volumio volumio[998]: info: CorePlayQueue::getTrack 0 May 05 00:51:53 volumio volumio[998]: info: [1746381113462] ControllerSpotify::clearAddPlayTrack May 05 00:51:53 volumio volumio[998]: info: Sending Spotify command with payload to local API: /player/play May 05 00:51:53 volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 05 00:51:53 volumio go-librespot[9821]: time="2025-05-05T00:51:53+07:00" level=debug msg="pause track at 128580ms" May 05 00:51:53 volumio go-librespot[9821]: time="2025-05-05T00:51:53+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 00:51:53 volumio go-librespot[9821]: time="2025-05-05T00:51:53+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 00:51:53 volumio go-librespot[9821]: time="2025-05-05T00:51:53+07:00" level=trace msg="emitting websocket event: paused" May 05 00:51:53 volumio volumio[998]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","play_origin":"playlist"}} May 05 00:51:53 volumio volumio[998]: info: Spotify is playing in volatile mode May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 00:51:53 volumio volumio[998]: SPOTIFY: UNSET VOLATILE May 05 00:51:53 volumio volumio[998]: SPOTIFY: {"status":"stop","position":0,"title":"กุหลาบ","artist":"F.HERO","album":"กุหลาบ","albumart":"https://i.scdn.co/image/ab67616d0000b2732874f11bddff3c6b6c9eca97","uri":"spotify:track:1uFvvFnus5WZFizuRH02Q0","trackType":"spotify","codec":"ogg","seek":0,"duration":235,"samplerate":"320 kbps","bitdepth":"16 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":95,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 05 00:51:53 volumio volumio[998]: SPOTIFY: PUSH STATE SPOTIFY May 05 00:51:53 volumio volumio[998]: 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} May 05 00:51:53 volumio volumio[998]: info: CoreCommandRouter::servicePushState May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::pushState May 05 00:51:53 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 00:51:53 volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 05 00:51:53 volumio volumio[998]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7yWEPokjQeePPTZD9RqxP4","play_origin":"playlist"}} May 05 00:51:53 volumio volumio[998]: info: Spotify is playing in volatile mode May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 00:51:53 volumio volumio[998]: SPOTIFY: UNSET VOLATILE May 05 00:51:53 volumio volumio[998]: SPOTIFY: {"status":"stop","position":0,"title":"กุหลาบ","artist":"F.HERO","album":"กุหลาบ","albumart":"https://i.scdn.co/image/ab67616d0000b2732874f11bddff3c6b6c9eca97","uri":"spotify:track:1uFvvFnus5WZFizuRH02Q0","trackType":"spotify","codec":"ogg","seek":0,"duration":235,"samplerate":"320 kbps","bitdepth":"16 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":95,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 05 00:51:53 volumio volumio[998]: SPOTIFY: PUSH STATE SPOTIFY May 05 00:51:53 volumio volumio[998]: 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,"stream":false,"repeatMode":"all"} May 05 00:51:53 volumio volumio[998]: info: CoreCommandRouter::servicePushState May 05 00:51:53 volumio volumio[998]: info: CoreStateMachine::pushState May 05 00:51:53 volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 05 00:51:53 volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 05 00:51:53 volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 05 00:51:53 volumio go-librespot[9821]: time="2025-05-05T00:51:53+07:00" level=debug msg="resolved context of track" uri="spotify:track:1uFvvFnus5WZFizuRH02Q0" May 05 00:51:53 volumio go-librespot[9821]: time="2025-05-05T00:51:53+07:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1uFvvFnus5WZFizuRH02Q0" May 05 00:51:53 volumio go-librespot[9821]: time="2025-05-05T00:51:53+07:00" level=debug msg="shuffled context with seed 4195607485091421767 (len: 1, keep: -1)" uri="spotify:track:1uFvvFnus5WZFizuRH02Q0" May 05 00:51:53 volumio go-librespot[9821]: time="2025-05-05T00:51:53+07:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1uFvvFnus5WZFizuRH02Q0" May 05 00:51:53 volumio go-librespot[9821]: time="2025-05-05T00:51:53+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 00:51:53 volumio go-librespot[9821]: time="2025-05-05T00:51:53+07:00" level=trace msg="emitting websocket event: will_play" May 05 00:51:53 volumio volumio[998]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1uFvvFnus5WZFizuRH02Q0","play_origin":"go-librespot"}} May 05 00:51:53 volumio volumio[998]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1uFvvFnus5WZFizuRH02Q0","play_origin":"go-librespot"}} May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=debug msg="selected format OGG_VORBIS_320 (97456386fc272c51e9fc008da83d636531f93ec0)" uri="spotify:track:1uFvvFnus5WZFizuRH02Q0" May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=debug msg="requested aes key for file 97456386fc272c51e9fc008da83d636531f93ec0, gid: 1uFvvFnus5WZFizuRH02Q0" May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=debug msg="fetched first chunk of 19, total size is 9701161 bytes" uri="spotify:track:1uFvvFnus5WZFizuRH02Q0" May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=info msg="loaded track \"กุหลาบ\" (paused: false, position: 0ms, duration: 235161ms, prefetched: false)" uri="spotify:track:1uFvvFnus5WZFizuRH02Q0" May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:1uFvvFnus5WZFizuRH02Q0" May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:1uFvvFnus5WZFizuRH02Q0" May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=trace msg="scheduling prefetch in 205s" May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=trace msg="emitting websocket event: metadata" May 05 00:51:54 volumio volumio[998]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1uFvvFnus5WZFizuRH02Q0","name":"กุหลาบ","artist_names":["F.HERO","Kantong Tungngern","Saran"],"album_name":"กุหลาบ","album_cover_url":"https://i.scdn.co/image/ab67616d00001e022874f11bddff3c6b6c9eca97","position":0,"duration":235161,"release_date":"year:2025 month:2 day:12","track_number":1,"disc_number":1}} May 05 00:51:54 volumio volumio[998]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1uFvvFnus5WZFizuRH02Q0","name":"กุหลาบ","artist_names":["F.HERO","Kantong Tungngern","Saran"],"album_name":"กุหลาบ","album_cover_url":"https://i.scdn.co/image/ab67616d00001e022874f11bddff3c6b6c9eca97","position":0,"duration":235161,"release_date":"year:2025 month:2 day:12","track_number":1,"disc_number":1}} May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:1uFvvFnus5WZFizuRH02Q0" May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=trace msg="emitting websocket event: playing" May 05 00:51:54 volumio volumio[998]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1uFvvFnus5WZFizuRH02Q0","play_origin":"go-librespot"}} May 05 00:51:54 volumio volumio[998]: SPOTIFY: PUSH STATE SPOTIFY May 05 00:51:54 volumio volumio[998]: SPOTIFY: {"status":"play","service":"spop","title":"กุหลาบ","artist":"F.HERO, Kantong Tungngern, Saran","album":"กุหลาบ","albumart":"https://i.scdn.co/image/ab67616d00001e022874f11bddff3c6b6c9eca97","uri":"spotify:track:1uFvvFnus5WZFizuRH02Q0","trackType":"spotify","seek":0,"duration":235,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 05 00:51:54 volumio volumio[998]: info: CoreCommandRouter::servicePushState May 05 00:51:54 volumio volumio[998]: info: CoreStateMachine::pushState May 05 00:51:54 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 00:51:54 volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 05 00:51:54 volumio volumio[998]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1uFvvFnus5WZFizuRH02Q0","play_origin":"go-librespot"}} May 05 00:51:54 volumio volumio[998]: SPOTIFY: PUSH STATE SPOTIFY May 05 00:51:54 volumio volumio[998]: SPOTIFY: {"status":"play","service":"spop","title":"กุหลาบ","artist":"F.HERO, Kantong Tungngern, Saran","album":"กุหลาบ","albumart":"https://i.scdn.co/image/ab67616d00001e022874f11bddff3c6b6c9eca97","uri":"spotify:track:1uFvvFnus5WZFizuRH02Q0","trackType":"spotify","seek":0,"duration":235,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 05 00:51:54 volumio volumio[998]: info: CoreCommandRouter::servicePushState May 05 00:51:54 volumio volumio[998]: info: CoreStateMachine::pushState May 05 00:51:54 volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 05 00:51:54 volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 05 00:51:54 volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=debug msg="skipping dealer message" uri=playback-settings/content-settings-update May 05 00:51:54 volumio go-librespot[9821]: time="2025-05-05T00:51:54+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 159" May 05 00:51:54 volumio volumio[998]: SPOTIFY: PUSH STATE SPOTIFY May 05 00:51:54 volumio volumio[998]: SPOTIFY: {"status":"play","service":"spop","title":"กุหลาบ","artist":"F.HERO, Kantong Tungngern, Saran","album":"กุหลาบ","albumart":"https://i.scdn.co/image/ab67616d00001e022874f11bddff3c6b6c9eca97","uri":"spotify:track:1uFvvFnus5WZFizuRH02Q0","trackType":"spotify","seek":0,"duration":235,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 05 00:51:54 volumio volumio[998]: info: CoreCommandRouter::servicePushState May 05 00:51:54 volumio volumio[998]: info: CoreStateMachine::pushState May 05 00:51:54 volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 05 00:51:54 volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 05 00:51:54 volumio volumio[998]: SPOTIFY: PUSH STATE SPOTIFY May 05 00:51:54 volumio volumio[998]: SPOTIFY: {"status":"play","service":"spop","title":"กุหลาบ","artist":"F.HERO, Kantong Tungngern, Saran","album":"กุหลาบ","albumart":"https://i.scdn.co/image/ab67616d00001e022874f11bddff3c6b6c9eca97","uri":"spotify:track:1uFvvFnus5WZFizuRH02Q0","trackType":"spotify","seek":0,"duration":235,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 05 00:51:54 volumio volumio[998]: info: CoreCommandRouter::servicePushState May 05 00:51:54 volumio volumio[998]: info: CoreStateMachine::pushState May 05 00:51:54 volumio volumio[998]: info: CoreCommandRouter::volumioPushState May 05 00:51:54 volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 May 05 00:52:07 volumio volumio[998]: info: CoreCommandRouter::volumioGetState May 05 00:52:09 volumio go-librespot[9821]: time="2025-05-05T00:52:09+07:00" level=debug msg="fetched chunk 4/18, size: 524288" uri="spotify:track:1uFvvFnus5WZFizuRH02Q0" May 05 00:52:10 volumio sudo[10625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 00:52:10 volumio sudo[10625]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 00:52:10 volumio sudo[10625]: pam_unix(sudo:session): session closed for user root May 05 00:52:10 volumio sudo[10628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 00:52:10 volumio sudo[10628]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 00:52:10 volumio sudo[10628]: pam_unix(sudo:session): session closed for user root May 05 00:52:10 volumio volumio[998]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.41 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::volumioGetVisibleSources May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::volumioGetState May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 00:52:10 volumio volumio[998]: info: Received Get System Info May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 00:52:10 volumio volumio[998]: info: Discovery: Getting this device information May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::volumioGetState May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::volumioGetState May 05 00:52:10 volumio volumio[998]: info: Listing playlists May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 05 00:52:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 00:52:11 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 05 00:52:11 volumio volumio[998]: info: [1746381131109] [80s80s] Pushing the next song state Michael Jackson - Will You Be There and getting next track. May 05 00:52:11 volumio volumio[998]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 00:52:11 volumio volumio[998]: TypeError: Cannot set property 'name' of undefined May 05 00:52:11 volumio volumio[998]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) May 05 00:52:11 volumio volumio[998]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) May 05 00:52:11 volumio volumio[998]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) May 05 00:52:11 volumio volumio[998]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) May 05 00:52:11 volumio volumio[998]: at processImmediate (internal/timers.js:461:21) May 05 00:52:11 volumio volumio[998]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 00:52:11 volumio go-librespot[9821]: time="2025-05-05T00:52:11+07:00" level=trace msg="sent dealer ping" May 05 00:52:11 volumio go-librespot[9821]: time="2025-05-05T00:52:11+07:00" level=trace msg="received dealer pong" May 05 00:52:12 volumio sudo[10642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-05 00:51 May 05 00:52:12 volumio sudo[10642]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"