-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Wed 2025-07-02 08:30:50 CST. -- Jul 02 08:29:07 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:07+08:00" level=trace msg="sent dealer ping" Jul 02 08:29:07 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:07+08:00" level=trace msg="received dealer pong" Jul 02 08:29:08 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jul 02 08:29:08 brian-volumio volumio[1183]: info: In handleBrowseUri, curUri=spotify Jul 02 08:29:08 brian-volumio volumio[1183]: info: Preload queue cleared Jul 02 08:29:08 brian-volumio volumio[1183]: info: Preload queue cleared Jul 02 08:29:08 brian-volumio volumio[1183]: info: Preload queue cleared Jul 02 08:29:08 brian-volumio volumio[1183]: info: Preload queue cleared Jul 02 08:29:09 brian-volumio volumiologrotate[641]: ls: cannot access '/var/log/samba/log.wb-BRIAN': No such file or directory Jul 02 08:29:09 brian-volumio volumiologrotate[641]: ls: cannot access 'VOLUMIO': No such file or directory Jul 02 08:29:20 brian-volumio volumio[1183]: Searching plugin music_service/spop Jul 02 08:29:20 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: spop , search Jul 02 08:29:20 brian-volumio volumio[1183]: info: All search sources collected, pushing search results Jul 02 08:29:25 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jul 02 08:29:25 brian-volumio volumio[1183]: info: In handleBrowseUri, curUri=spotify:album:4J3XjEzUvDKGNyCESR2Jls Jul 02 08:29:25 brian-volumio volumio[1183]: info: Preload queue cleared Jul 02 08:29:25 brian-volumio volumio[1183]: info: Preloading song: spotify:track:0htep22LKT8dBKM89oKBYT Jul 02 08:29:25 brian-volumio volumio[1183]: info: Preloading song: spotify:track:4iOeNGGUNms7gNViydA70S Jul 02 08:29:25 brian-volumio volumio[1183]: info: Preloading song: spotify:track:2wSDpRiaWmLTzMXv4Jt0YX Jul 02 08:29:25 brian-volumio volumio[1183]: info: Preloading song: spotify:track:4Om9qnR3ZCros1AC43jkW3 Jul 02 08:29:25 brian-volumio volumio[1183]: info: Preloading song: spotify:track:497wju8q8XlupqaLq7yMkz Jul 02 08:29:25 brian-volumio volumio[1183]: info: Preloading song: spotify:track:1cuoSfqp2bxYLpmk6k9ryv Jul 02 08:29:25 brian-volumio volumio[1183]: info: Preloading song: spotify:track:4pp3H1juhrzNqvkCAXDjNh Jul 02 08:29:25 brian-volumio volumio[1183]: info: Preloading song: spotify:track:48CnX8v6OD9kqmeTIaLS1k Jul 02 08:29:25 brian-volumio volumio[1183]: info: Preloading song: spotify:track:1IDQXWGLzpLIAvMHJIMgU5 Jul 02 08:29:25 brian-volumio volumio[1183]: info: Preloading song: spotify:track:2ACgdziysdvHdoc4N2LvH2 Jul 02 08:29:25 brian-volumio volumio[1183]: info: Exploding uri spotify:track:0htep22LKT8dBKM89oKBYT in service spop Jul 02 08:29:25 brian-volumio volumio[1183]: SPOTIFY: EXPLODING URI:spotify:track:0htep22LKT8dBKM89oKBYT Jul 02 08:29:25 brian-volumio volumio[1183]: info: Exploding uri spotify:track:4iOeNGGUNms7gNViydA70S in service spop Jul 02 08:29:25 brian-volumio volumio[1183]: SPOTIFY: EXPLODING URI:spotify:track:4iOeNGGUNms7gNViydA70S Jul 02 08:29:25 brian-volumio volumio[1183]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0htep22LKT8dBKM89oKBYT","service":"spop","name":"天空","artist":"Faye Wong","album":"天空","type":"song","duration":259,"albumart":"https://i.scdn.co/image/ab67616d0000b2735cb1d2103dea499cb64c28f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 02 08:29:26 brian-volumio volumio[1183]: info: Exploding uri spotify:track:2wSDpRiaWmLTzMXv4Jt0YX in service spop Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: EXPLODING URI:spotify:track:2wSDpRiaWmLTzMXv4Jt0YX Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4iOeNGGUNms7gNViydA70S","service":"spop","name":"棋子","artist":"Faye Wong","album":"天空","type":"song","duration":286,"albumart":"https://i.scdn.co/image/ab67616d0000b2735cb1d2103dea499cb64c28f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 02 08:29:26 brian-volumio volumio[1183]: info: Exploding uri spotify:track:4Om9qnR3ZCros1AC43jkW3 in service spop Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: EXPLODING URI:spotify:track:4Om9qnR3ZCros1AC43jkW3 Jul 02 08:29:26 brian-volumio volumio[1183]: info: Executing endpoint getSimilarAlbums Jul 02 08:29:26 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 02 08:29:26 brian-volumio volumio[1183]: info: Executing endpoint getSimilarAlbums Jul 02 08:29:26 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 02 08:29:26 brian-volumio volumio[1183]: info: Executing endpoint getSimilarAlbums Jul 02 08:29:26 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2wSDpRiaWmLTzMXv4Jt0YX","service":"spop","name":"天使","artist":"Faye Wong","album":"天空","type":"song","duration":263,"albumart":"https://i.scdn.co/image/ab67616d0000b2735cb1d2103dea499cb64c28f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 02 08:29:26 brian-volumio volumio[1183]: info: Exploding uri spotify:track:497wju8q8XlupqaLq7yMkz in service spop Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: EXPLODING URI:spotify:track:497wju8q8XlupqaLq7yMkz Jul 02 08:29:26 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:29:26 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:29:26 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:29:26 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:29:26 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:29:26 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:29:26 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:29:26 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:29:26 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:29:26 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:29:26 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:29:26 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:29:26 brian-volumio volumio[1183]: info: Exploding uri spotify:track:1cuoSfqp2bxYLpmk6k9ryv in service spop Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: EXPLODING URI:spotify:track:1cuoSfqp2bxYLpmk6k9ryv Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Om9qnR3ZCros1AC43jkW3","service":"spop","name":"影子","artist":"Faye Wong","album":"天空","type":"song","duration":294,"albumart":"https://i.scdn.co/image/ab67616d0000b2735cb1d2103dea499cb64c28f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:497wju8q8XlupqaLq7yMkz","service":"spop","name":"天空 - Unplugged","artist":"Faye Wong","album":"天空","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b2735cb1d2103dea499cb64c28f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 02 08:29:26 brian-volumio volumio[1183]: info: Exploding uri spotify:track:4pp3H1juhrzNqvkCAXDjNh in service spop Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: EXPLODING URI:spotify:track:4pp3H1juhrzNqvkCAXDjNh Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1cuoSfqp2bxYLpmk6k9ryv","service":"spop","name":"眷戀","artist":"Faye Wong","album":"天空","type":"song","duration":238,"albumart":"https://i.scdn.co/image/ab67616d0000b2735cb1d2103dea499cb64c28f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 02 08:29:26 brian-volumio volumio[1183]: info: Exploding uri spotify:track:48CnX8v6OD9kqmeTIaLS1k in service spop Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: EXPLODING URI:spotify:track:48CnX8v6OD9kqmeTIaLS1k Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4pp3H1juhrzNqvkCAXDjNh","service":"spop","name":"不變","artist":"Faye Wong","album":"天空","type":"song","duration":285,"albumart":"https://i.scdn.co/image/ab67616d0000b2735cb1d2103dea499cb64c28f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 02 08:29:26 brian-volumio volumio[1183]: info: Exploding uri spotify:track:1IDQXWGLzpLIAvMHJIMgU5 in service spop Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: EXPLODING URI:spotify:track:1IDQXWGLzpLIAvMHJIMgU5 Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:48CnX8v6OD9kqmeTIaLS1k","service":"spop","name":"矜持","artist":"Faye Wong","album":"天空","type":"song","duration":277,"albumart":"https://i.scdn.co/image/ab67616d0000b2735cb1d2103dea499cb64c28f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 02 08:29:26 brian-volumio volumio[1183]: info: Exploding uri spotify:track:2ACgdziysdvHdoc4N2LvH2 in service spop Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: EXPLODING URI:spotify:track:2ACgdziysdvHdoc4N2LvH2 Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1IDQXWGLzpLIAvMHJIMgU5","service":"spop","name":"掙脫","artist":"Faye Wong","album":"天空","type":"song","duration":268,"albumart":"https://i.scdn.co/image/ab67616d0000b2735cb1d2103dea499cb64c28f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 02 08:29:26 brian-volumio volumio[1183]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ACgdziysdvHdoc4N2LvH2","service":"spop","name":"誓言","artist":"Faye Wong","album":"天空","type":"song","duration":261,"albumart":"https://i.scdn.co/image/ab67616d0000b2735cb1d2103dea499cb64c28f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jul 02 08:29:30 brian-volumio volumio[1183]: info: Preload queue cleared Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::ClearQueue Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::stop Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::stPlaybackTimer Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::updateTrackBlock Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrackBlock Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::pushState Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushState Jul 02 08:29:30 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output update for this device Jul 02 08:29:30 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetState Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::serviceStop Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::serviceStop Jul 02 08:29:30 brian-volumio volumio[1183]: info: [1751416170083] ControllerTidal::stop Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 08:29:30 brian-volumio volumio[1183]: info: ControllerMpd::stop Jul 02 08:29:30 brian-volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::clearPlayQueue Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::saveQueue Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushQueue Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::addQueueItems Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::addQueueItems Jul 02 08:29:30 brian-volumio volumio[1183]: info: Preload queue cleared Jul 02 08:29:30 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:0htep22LKT8dBKM89oKBYT Jul 02 08:29:30 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:0htep22LKT8dBKM89oKBYT Jul 02 08:29:30 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:4iOeNGGUNms7gNViydA70S Jul 02 08:29:30 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:4iOeNGGUNms7gNViydA70S Jul 02 08:29:30 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:2wSDpRiaWmLTzMXv4Jt0YX Jul 02 08:29:30 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:2wSDpRiaWmLTzMXv4Jt0YX Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushQueue Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::saveQueue Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::updateTrackBlock Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrackBlock Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPlay Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::play index 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::addQueueItems Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::addQueueItems Jul 02 08:29:30 brian-volumio volumio[1183]: info: Preload queue cleared Jul 02 08:29:30 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:4Om9qnR3ZCros1AC43jkW3 Jul 02 08:29:30 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:4Om9qnR3ZCros1AC43jkW3 Jul 02 08:29:30 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:497wju8q8XlupqaLq7yMkz Jul 02 08:29:30 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:497wju8q8XlupqaLq7yMkz Jul 02 08:29:30 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:1cuoSfqp2bxYLpmk6k9ryv Jul 02 08:29:30 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:1cuoSfqp2bxYLpmk6k9ryv Jul 02 08:29:30 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:4pp3H1juhrzNqvkCAXDjNh Jul 02 08:29:30 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:4pp3H1juhrzNqvkCAXDjNh Jul 02 08:29:30 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:48CnX8v6OD9kqmeTIaLS1k Jul 02 08:29:30 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:48CnX8v6OD9kqmeTIaLS1k Jul 02 08:29:30 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:1IDQXWGLzpLIAvMHJIMgU5 Jul 02 08:29:30 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:1IDQXWGLzpLIAvMHJIMgU5 Jul 02 08:29:30 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:2ACgdziysdvHdoc4N2LvH2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:2ACgdziysdvHdoc4N2LvH2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::stop Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushQueue Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::saveQueue Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::play index undefined Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::updateTrackBlock Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrackBlock Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::startPlaybackTimer Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: [1751416170119] ControllerSpotify::clearAddPlayTrack Jul 02 08:29:30 brian-volumio volumio[1183]: info: Sending Spotify command with payload to local API: /player/play Jul 02 08:29:30 brian-volumio volumio[1183]: info: Jul 02 08:29:30 brian-volumio volumio[1183]: ---------------------------- MPD announces state update: player Jul 02 08:29:30 brian-volumio volumio[1183]: info: sendMpdCommand stop took 46 milliseconds Jul 02 08:29:30 brian-volumio volumio[1183]: info: ControllerMpd::getState Jul 02 08:29:30 brian-volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status Jul 02 08:29:30 brian-volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds Jul 02 08:29:30 brian-volumio volumio[1183]: verbose: ControllerMpd::parseState Jul 02 08:29:30 brian-volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 08:29:30 brian-volumio volumio[1183]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 08:29:30 brian-volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo Jul 02 08:29:30 brian-volumio volumio[1183]: info: ControllerMpd::pushState Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::servicePushState Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::pushState Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushState Jul 02 08:29:30 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output update for this device Jul 02 08:29:30 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetState Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jul 02 08:29:30 brian-volumio volumio[1183]: info: ------------------------------ 22ms Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="resolved context of track" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=trace msg="emitting websocket event: will_play" Jul 02 08:29:30 brian-volumio volumio[1183]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2wSDpRiaWmLTzMXv4Jt0YX","play_origin":"go-librespot"}} Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="selected format OGG_VORBIS_320 (701468654777321e882261c9740bcbe0790ffbab)" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="requested aes key for file 701468654777321e882261c9740bcbe0790ffbab, gid: 2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1374" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="fetched first chunk of 22, total size is 11424580 bytes" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="created new output device" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08: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" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=info msg="loaded track \"天使\" (paused: false, position: 0ms, duration: 263120ms, prefetched: false)" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=trace msg="scheduling prefetch in 233s" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=trace msg="emitting websocket event: metadata" Jul 02 08:29:30 brian-volumio volumio[1183]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2wSDpRiaWmLTzMXv4Jt0YX","name":"天使","artist_names":["Faye Wong"],"album_name":"天空","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025cb1d2103dea499cb64c28f3","position":0,"duration":263120,"release_date":"year:1994 month:1 day:1","track_number":3,"disc_number":1}} Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=trace msg="emitting websocket event: playing" Jul 02 08:29:30 brian-volumio volumio[1183]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2wSDpRiaWmLTzMXv4Jt0YX","play_origin":"go-librespot"}} Jul 02 08:29:30 brian-volumio volumio[1183]: SPOTIFY: PUSH STATE SPOTIFY Jul 02 08:29:30 brian-volumio volumio[1183]: SPOTIFY: {"status":"play","service":"spop","title":"天使","artist":"Faye Wong","album":"天空","albumart":"https://i.scdn.co/image/ab67616d00001e025cb1d2103dea499cb64c28f3","uri":"spotify:track:2wSDpRiaWmLTzMXv4Jt0YX","trackType":"spotify","seek":0,"duration":263,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::servicePushState Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"天使","artist":"Faye Wong","album":"天空","albumart":"https://i.scdn.co/image/ab67616d00001e025cb1d2103dea499cb64c28f3","uri":"spotify:track:2wSDpRiaWmLTzMXv4Jt0YX","trackType":"spotify","seek":0,"duration":263,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 02 08:29:30 brian-volumio volumio[1183]: verbose: CURRENT POSITION 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::syncState stateService play Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1134" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:29:30 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:30+08:00" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:29:30 brian-volumio volumio[1183]: SPOTIFY: PUSH STATE SPOTIFY Jul 02 08:29:30 brian-volumio volumio[1183]: SPOTIFY: {"status":"play","service":"spop","title":"天使","artist":"Faye Wong","album":"天空","albumart":"https://i.scdn.co/image/ab67616d00001e025cb1d2103dea499cb64c28f3","uri":"spotify:track:2wSDpRiaWmLTzMXv4Jt0YX","trackType":"spotify","seek":0,"duration":263,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::servicePushState Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"天使","artist":"Faye Wong","album":"天空","albumart":"https://i.scdn.co/image/ab67616d00001e025cb1d2103dea499cb64c28f3","uri":"spotify:track:2wSDpRiaWmLTzMXv4Jt0YX","trackType":"spotify","seek":0,"duration":263,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 02 08:29:30 brian-volumio volumio[1183]: verbose: CURRENT POSITION 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::syncState stateService play Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play Jul 02 08:29:30 brian-volumio volumio[1183]: info: Received an update from plugin. extracting info from payload Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::pushState Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushState Jul 02 08:29:30 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output update for this device Jul 02 08:29:30 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetState Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreStateMachine::pushState Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushState Jul 02 08:29:30 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output update for this device Jul 02 08:29:30 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output Jul 02 08:29:30 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetState Jul 02 08:29:30 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:34 brian-volumio volumio[1183]: info: VolumeController::SetAlsaVolume100 Jul 02 08:29:34 brian-volumio volumio[1183]: info: CoreStateMachine::pushState Jul 02 08:29:34 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:34 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 08:29:34 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushState Jul 02 08:29:34 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output update for this device Jul 02 08:29:34 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output Jul 02 08:29:34 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetState Jul 02 08:29:34 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:35 brian-volumio volumio[1183]: info: VolumeController::SetAlsaVolume100 Jul 02 08:29:35 brian-volumio volumio[1183]: info: CoreStateMachine::pushState Jul 02 08:29:35 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:35 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 08:29:35 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushState Jul 02 08:29:35 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output update for this device Jul 02 08:29:35 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output Jul 02 08:29:35 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetState Jul 02 08:29:35 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:29:37 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:37+08:00" level=trace msg="sent dealer ping" Jul 02 08:29:37 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:37+08:00" level=trace msg="received dealer pong" Jul 02 08:29:41 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:41+08:00" level=debug msg="fetched chunk 4/21, size: 524288" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:29:53 brian-volumio go-librespot[1365]: time="2025-07-02T08:29:53+08:00" level=debug msg="fetched chunk 5/21, size: 524288" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:30:00 brian-volumio volumio[1183]: info: VolumeController::SetAlsaVolume100 Jul 02 08:30:00 brian-volumio volumio[1183]: info: CoreStateMachine::pushState Jul 02 08:30:00 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:30:00 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 08:30:00 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushState Jul 02 08:30:00 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output update for this device Jul 02 08:30:00 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output Jul 02 08:30:00 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetState Jul 02 08:30:00 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:30:01 brian-volumio volumio[1183]: info: VolumeController::SetAlsaVolume100 Jul 02 08:30:01 brian-volumio volumio[1183]: info: CoreStateMachine::pushState Jul 02 08:30:01 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:30:01 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 08:30:01 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushState Jul 02 08:30:01 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output update for this device Jul 02 08:30:01 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output Jul 02 08:30:01 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetState Jul 02 08:30:01 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:30:06 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:06+08:00" level=debug msg="fetched chunk 6/21, size: 524288" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:30:07 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:07+08:00" level=trace msg="sent dealer ping" Jul 02 08:30:07 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:07+08:00" level=trace msg="received dealer pong" Jul 02 08:30:09 brian-volumio volumiologrotate[641]: ls: cannot access '/var/log/samba/log.wb-BRIAN': No such file or directory Jul 02 08:30:09 brian-volumio volumiologrotate[641]: ls: cannot access 'VOLUMIO': No such file or directory Jul 02 08:30:14 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:30:14 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:30:14 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:30:14 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:30:15 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:30:15 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: spop , goto Jul 02 08:30:15 brian-volumio volumio[1183]: info: Executing endpoint getSimilarAlbums Jul 02 08:30:15 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 02 08:30:15 brian-volumio volumio[1183]: info: Executing endpoint getSimilarAlbums Jul 02 08:30:15 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 02 08:30:15 brian-volumio volumio[1183]: info: Executing endpoint getSimilarAlbums Jul 02 08:30:15 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 02 08:30:15 brian-volumio volumio[1183]: info: Executing endpoint getSimilarAlbums Jul 02 08:30:15 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jul 02 08:30:15 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:30:15 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:30:15 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:30:15 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:30:15 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:30:15 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:30:15 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:30:15 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:30:17 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:30:17 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:30:17 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:30:17 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:30:17 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:30:17 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:30:17 brian-volumio volumio[1183]: info: Executing endpoint metavolumio Jul 02 08:30:17 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 02 08:30:18 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:18+08:00" level=debug msg="fetched chunk 7/21, size: 524288" uri="spotify:track:2wSDpRiaWmLTzMXv4Jt0YX" Jul 02 08:30:19 brian-volumio volumio[1183]: info: Preload queue cleared Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::ClearQueue Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::stop Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::stPlaybackTimer Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::updateTrackBlock Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrackBlock Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::pushState Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushState Jul 02 08:30:19 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output update for this device Jul 02 08:30:19 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetState Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::serviceStop Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 2 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::serviceStop Jul 02 08:30:19 brian-volumio volumio[1183]: info: Spotify Stop Jul 02 08:30:19 brian-volumio volumio[1183]: SPOTIFY: SPOTIFY STOP Jul 02 08:30:19 brian-volumio volumio[1183]: SPOTIFY: {"status":"play","position":2,"title":"天使","artist":"Faye Wong","album":"天空","albumart":"https://i.scdn.co/image/ab67616d0000b2735cb1d2103dea499cb64c28f3","uri":"spotify:track:2wSDpRiaWmLTzMXv4Jt0YX","trackType":"spotify","codec":"ogg","seek":30273,"duration":263,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jul 02 08:30:19 brian-volumio volumio[1183]: info: Sending Spotify command to local API: /player/pause Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::clearPlayQueue Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::saveQueue Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushQueue Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::addQueueItems Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::addQueueItems Jul 02 08:30:19 brian-volumio volumio[1183]: info: Preload queue cleared Jul 02 08:30:19 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:0htep22LKT8dBKM89oKBYT Jul 02 08:30:19 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:0htep22LKT8dBKM89oKBYT Jul 02 08:30:19 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:4iOeNGGUNms7gNViydA70S Jul 02 08:30:19 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:4iOeNGGUNms7gNViydA70S Jul 02 08:30:19 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:2wSDpRiaWmLTzMXv4Jt0YX Jul 02 08:30:19 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:2wSDpRiaWmLTzMXv4Jt0YX Jul 02 08:30:19 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:4Om9qnR3ZCros1AC43jkW3 Jul 02 08:30:19 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:4Om9qnR3ZCros1AC43jkW3 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushQueue Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::saveQueue Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::updateTrackBlock Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrackBlock Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPlay Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::play index 3 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::addQueueItems Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::addQueueItems Jul 02 08:30:19 brian-volumio volumio[1183]: info: Preload queue cleared Jul 02 08:30:19 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:497wju8q8XlupqaLq7yMkz Jul 02 08:30:19 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:497wju8q8XlupqaLq7yMkz Jul 02 08:30:19 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:1cuoSfqp2bxYLpmk6k9ryv Jul 02 08:30:19 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:1cuoSfqp2bxYLpmk6k9ryv Jul 02 08:30:19 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:4pp3H1juhrzNqvkCAXDjNh Jul 02 08:30:19 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:4pp3H1juhrzNqvkCAXDjNh Jul 02 08:30:19 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:48CnX8v6OD9kqmeTIaLS1k Jul 02 08:30:19 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:48CnX8v6OD9kqmeTIaLS1k Jul 02 08:30:19 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:1IDQXWGLzpLIAvMHJIMgU5 Jul 02 08:30:19 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:1IDQXWGLzpLIAvMHJIMgU5 Jul 02 08:30:19 brian-volumio volumio[1183]: info: Adding Item to queue: spotify:track:2ACgdziysdvHdoc4N2LvH2 Jul 02 08:30:19 brian-volumio volumio[1183]: info: Using cached record of: spotify:track:2ACgdziysdvHdoc4N2LvH2 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::stop Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushQueue Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::saveQueue Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::play index undefined Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::updateTrackBlock Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrackBlock Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 3 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::startPlaybackTimer Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 3 Jul 02 08:30:19 brian-volumio volumio[1183]: info: [1751416219220] ControllerSpotify::clearAddPlayTrack Jul 02 08:30:19 brian-volumio volumio[1183]: info: Sending Spotify command with payload to local API: /player/play Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="pause track at 48625ms" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 02 08:30:19 brian-volumio volumio[1183]: error: Failed request for metavolumio API Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=trace msg="emitting websocket event: paused" Jul 02 08:30:19 brian-volumio volumio[1183]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2wSDpRiaWmLTzMXv4Jt0YX","play_origin":"go-librespot"}} Jul 02 08:30:19 brian-volumio volumio[1183]: SPOTIFY: PUSH STATE SPOTIFY Jul 02 08:30:19 brian-volumio volumio[1183]: 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} Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::servicePushState Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 3 Jul 02 08:30:19 brian-volumio volumio[1183]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 02 08:30:19 brian-volumio volumio[1183]: verbose: CURRENT POSITION 3 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::syncState stateService pause Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::pushState Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 3 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushState Jul 02 08:30:19 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output update for this device Jul 02 08:30:19 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetState Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 3 Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="resolved context of track" uri="spotify:track:4Om9qnR3ZCros1AC43jkW3" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4Om9qnR3ZCros1AC43jkW3" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4Om9qnR3ZCros1AC43jkW3" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=trace msg="emitting websocket event: will_play" Jul 02 08:30:19 brian-volumio volumio[1183]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4Om9qnR3ZCros1AC43jkW3","play_origin":"go-librespot"}} Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="selected format OGG_VORBIS_320 (3c783eb4e93bf3439ffc8323635651d2c0115b18)" uri="spotify:track:4Om9qnR3ZCros1AC43jkW3" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="requested aes key for file 3c783eb4e93bf3439ffc8323635651d2c0115b18, gid: 4Om9qnR3ZCros1AC43jkW3" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="fetched first chunk of 25, total size is 12598264 bytes" uri="spotify:track:4Om9qnR3ZCros1AC43jkW3" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08: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" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=info msg="loaded track \"影子\" (paused: false, position: 0ms, duration: 294493ms, prefetched: false)" uri="spotify:track:4Om9qnR3ZCros1AC43jkW3" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=trace msg="scheduling prefetch in 264s" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=trace msg="emitting websocket event: metadata" Jul 02 08:30:19 brian-volumio volumio[1183]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4Om9qnR3ZCros1AC43jkW3","name":"影子","artist_names":["Faye Wong"],"album_name":"天空","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025cb1d2103dea499cb64c28f3","position":0,"duration":294493,"release_date":"year:1994 month:1 day:1","track_number":4,"disc_number":1}} Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="fetched chunk 1/24, size: 524288" uri="spotify:track:4Om9qnR3ZCros1AC43jkW3" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=trace msg="emitting websocket event: playing" Jul 02 08:30:19 brian-volumio volumio[1183]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4Om9qnR3ZCros1AC43jkW3","play_origin":"go-librespot"}} Jul 02 08:30:19 brian-volumio volumio[1183]: SPOTIFY: PUSH STATE SPOTIFY Jul 02 08:30:19 brian-volumio volumio[1183]: SPOTIFY: {"status":"play","service":"spop","title":"影子","artist":"Faye Wong","album":"天空","albumart":"https://i.scdn.co/image/ab67616d00001e025cb1d2103dea499cb64c28f3","uri":"spotify:track:4Om9qnR3ZCros1AC43jkW3","trackType":"spotify","seek":0,"duration":294,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::servicePushState Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 3 Jul 02 08:30:19 brian-volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"影子","artist":"Faye Wong","album":"天空","albumart":"https://i.scdn.co/image/ab67616d00001e025cb1d2103dea499cb64c28f3","uri":"spotify:track:4Om9qnR3ZCros1AC43jkW3","trackType":"spotify","seek":0,"duration":294,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 02 08:30:19 brian-volumio volumio[1183]: verbose: CURRENT POSITION 3 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::syncState stateService play Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="fetched chunk 3/24, size: 524288" uri="spotify:track:4Om9qnR3ZCros1AC43jkW3" Jul 02 08:30:19 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:19+08:00" level=debug msg="fetched chunk 2/24, size: 524288" uri="spotify:track:4Om9qnR3ZCros1AC43jkW3" Jul 02 08:30:19 brian-volumio volumio[1183]: SPOTIFY: PUSH STATE SPOTIFY Jul 02 08:30:19 brian-volumio volumio[1183]: SPOTIFY: {"status":"play","service":"spop","title":"影子","artist":"Faye Wong","album":"天空","albumart":"https://i.scdn.co/image/ab67616d00001e025cb1d2103dea499cb64c28f3","uri":"spotify:track:4Om9qnR3ZCros1AC43jkW3","trackType":"spotify","seek":0,"duration":294,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::servicePushState Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 3 Jul 02 08:30:19 brian-volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"影子","artist":"Faye Wong","album":"天空","albumart":"https://i.scdn.co/image/ab67616d00001e025cb1d2103dea499cb64c28f3","uri":"spotify:track:4Om9qnR3ZCros1AC43jkW3","trackType":"spotify","seek":0,"duration":294,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 02 08:30:19 brian-volumio volumio[1183]: verbose: CURRENT POSITION 3 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::syncState stateService play Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play Jul 02 08:30:19 brian-volumio volumio[1183]: info: Received an update from plugin. extracting info from payload Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::pushState Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 3 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushState Jul 02 08:30:19 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output update for this device Jul 02 08:30:19 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetState Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 3 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreStateMachine::pushState Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 3 Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioPushState Jul 02 08:30:19 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output update for this device Jul 02 08:30:19 brian-volumio volumio[1183]: info: MRS: Pushing multiroomSync output Jul 02 08:30:19 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetState Jul 02 08:30:19 brian-volumio volumio[1183]: info: CorePlayQueue::getTrack 3 Jul 02 08:30:31 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:31+08:00" level=debug msg="fetched chunk 4/24, size: 524288" uri="spotify:track:4Om9qnR3ZCros1AC43jkW3" Jul 02 08:30:37 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:37+08:00" level=trace msg="received accesspoint ping" Jul 02 08:30:37 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:37+08:00" level=trace msg="received accesspoint pong ack" Jul 02 08:30:37 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:37+08:00" level=trace msg="sent dealer ping" Jul 02 08:30:37 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:37+08:00" level=trace msg="received dealer pong" Jul 02 08:30:43 brian-volumio go-librespot[1365]: time="2025-07-02T08:30:43+08:00" level=debug msg="fetched chunk 5/24, size: 524288" uri="spotify:track:4Om9qnR3ZCros1AC43jkW3" Jul 02 08:30:45 brian-volumio volumio[1183]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 08:30:45 brian-volumio volumio[1183]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 08:30:45 brian-volumio volumio[1183]: info: Retrieving Cloud Streaming UI Jul 02 08:30:45 brian-volumio volumio[1183]: info: Getting Tidal Cloud Configuration Jul 02 08:30:45 brian-volumio volumio[1183]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 08:30:45 brian-volumio volumio[1183]: info: Getting Qobuz Cloud Configuration Jul 02 08:30:45 brian-volumio volumio[1183]: info: Asking plugin for UI Config Jul 02 08:30:45 brian-volumio volumio[1183]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 08:30:45 brian-volumio volumio[1183]: info: Getting Spotify Cloud Configuration Jul 02 08:30:45 brian-volumio volumio[1183]: info: Asking plugin for UI Config Jul 02 08:30:45 brian-volumio volumio[1183]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 08:30:45 brian-volumio volumio[1183]: info: Saving Spotify Acccount Jul 02 08:30:45 brian-volumio volumio[1183]: info: Got it Jul 02 08:30:45 brian-volumio volumio[1183]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 02 08:30:45 brian-volumio volumio[1183]: info: Got it Jul 02 08:30:45 brian-volumio volumio[1183]: info: Got Tidal Cloud Configuration Jul 02 08:30:45 brian-volumio volumio[1183]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 08:30:45 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 02 08:30:45 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 02 08:30:45 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 08:30:45 brian-volumio volumio[1183]: info: CoreCommandRouter::volumioGetBrowseSources Jul 02 08:30:45 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 08:30:45 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 02 08:30:48 brian-volumio volumio[1183]: info: Disabling MyMusic plugin upnp Jul 02 08:30:48 brian-volumio sudo[3992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jul 02 08:30:48 brian-volumio sudo[3992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 08:30:48 brian-volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Jul 02 08:30:48 brian-volumio volumio[1183]: error: Upnp client error: Error: This socket has been ended by the other party Jul 02 08:30:48 brian-volumio volumio[1183]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 02 08:30:49 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 08:30:50 brian-volumio volumio[1183]: info: Enabling MyMusic plugin upnp Jul 02 08:30:50 brian-volumio volumio[1183]: info: Enabling plugin upnp Jul 02 08:30:50 brian-volumio volumio[1183]: info: Loading plugin "upnp"... Jul 02 08:30:50 brian-volumio volumio[1183]: info: [1751416250480] Starting Upmpd Daemon Jul 02 08:30:50 brian-volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 02 08:30:50 brian-volumio volumio[1183]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 08:30:50 brian-volumio volumio[1183]: Error: listen EADDRINUSE: address already in use :::6599 Jul 02 08:30:50 brian-volumio volumio[1183]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Jul 02 08:30:50 brian-volumio volumio[1183]: at listenInCluster (net.js:1366:12) Jul 02 08:30:50 brian-volumio volumio[1183]: at Server.listen (net.js:1452:7) Jul 02 08:30:50 brian-volumio volumio[1183]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jul 02 08:30:50 brian-volumio volumio[1183]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jul 02 08:30:50 brian-volumio volumio[1183]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 02 08:30:50 brian-volumio volumio[1183]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 02 08:30:50 brian-volumio volumio[1183]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jul 02 08:30:50 brian-volumio volumio[1183]: code: 'EADDRINUSE', Jul 02 08:30:50 brian-volumio volumio[1183]: errno: -98, Jul 02 08:30:50 brian-volumio volumio[1183]: syscall: 'listen', Jul 02 08:30:50 brian-volumio volumio[1183]: address: '::', Jul 02 08:30:50 brian-volumio volumio[1183]: port: 6599 Jul 02 08:30:50 brian-volumio volumio[1183]: } Jul 02 08:30:50 brian-volumio volumio[1183]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 08:30:50 brian-volumio sudo[4003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 08:29 Jul 02 08:30:50 brian-volumio sudo[4003]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"