Apr 11 17:23:20 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:20 volumio volumio[21995]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 17:23:20 volumio volumio[21995]: info: CoreStateMachine::ClearQueue Apr 11 17:23:20 volumio volumio[21995]: info: CoreStateMachine::stop Apr 11 17:23:20 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:20 volumio volumio[21995]: info: CorePlayQueue::clearPlayQueue Apr 11 17:23:20 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:20 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:20 volumio volumio[21995]: info: CoreStateMachine::addQueueItems Apr 11 17:23:20 volumio volumio[21995]: info: CorePlayQueue::addQueueItems Apr 11 17:23:20 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:20 volumio volumio[21995]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1DX8xfQRRX1PDm Apr 11 17:23:20 volumio volumio[21995]: info: Exploding uri spotify:user:spotify:playlist:37i9dQZF1DX8xfQRRX1PDm in service spop Apr 11 17:23:20 volumio volumio[21995]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:37i9dQZF1DX8xfQRRX1PDm Apr 11 17:23:21 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:21 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:21 volumio volumio[21995]: info: CoreStateMachine::updateTrackBlock Apr 11 17:23:21 volumio volumio[21995]: info: CorePlayQueue::getTrackBlock Apr 11 17:23:21 volumio volumio[21995]: info: CoreCommandRouter::volumioPlay Apr 11 17:23:21 volumio volumio[21995]: info: CoreStateMachine::play index 0 Apr 11 17:23:21 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:21 volumio volumio[21995]: info: CoreStateMachine::stop Apr 11 17:23:21 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:21 volumio volumio[21995]: info: CoreStateMachine::play index undefined Apr 11 17:23:21 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:21 volumio volumio[21995]: info: CorePlayQueue::getTrack 0 Apr 11 17:23:21 volumio volumio[21995]: info: CoreStateMachine::startPlaybackTimer Apr 11 17:23:21 volumio volumio[21995]: info: CorePlayQueue::getTrack 0 Apr 11 17:23:21 volumio volumio[21995]: info: [1775903001420] ControllerSpotify::clearAddPlayTrack Apr 11 17:23:21 volumio volumio[21995]: info: Sending Spotify command with payload to local API: /player/play Apr 11 17:23:23 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:23 volumio volumio[21995]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 17:23:23 volumio volumio[21995]: info: CoreStateMachine::ClearQueue Apr 11 17:23:23 volumio volumio[21995]: info: CoreStateMachine::stop Apr 11 17:23:23 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:23 volumio volumio[21995]: info: CorePlayQueue::clearPlayQueue Apr 11 17:23:23 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:23 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:23 volumio volumio[21995]: info: CoreStateMachine::addQueueItems Apr 11 17:23:23 volumio volumio[21995]: info: CorePlayQueue::addQueueItems Apr 11 17:23:23 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:23 volumio volumio[21995]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1DX8xfQRRX1PDm Apr 11 17:23:23 volumio volumio[21995]: info: Using cached record of: spotify:user:spotify:playlist:37i9dQZF1DX8xfQRRX1PDm Apr 11 17:23:23 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:23 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:23 volumio volumio[21995]: info: CoreStateMachine::updateTrackBlock Apr 11 17:23:23 volumio volumio[21995]: info: CorePlayQueue::getTrackBlock Apr 11 17:23:23 volumio volumio[21995]: info: CoreCommandRouter::volumioPlay Apr 11 17:23:23 volumio volumio[21995]: info: CoreStateMachine::play index 0 Apr 11 17:23:23 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:23 volumio volumio[21995]: info: CoreStateMachine::stop Apr 11 17:23:23 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:23 volumio volumio[21995]: info: CoreStateMachine::play index undefined Apr 11 17:23:23 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:23 volumio volumio[21995]: info: CorePlayQueue::getTrack 0 Apr 11 17:23:23 volumio volumio[21995]: info: CoreStateMachine::startPlaybackTimer Apr 11 17:23:23 volumio volumio[21995]: info: CorePlayQueue::getTrack 0 Apr 11 17:23:23 volumio volumio[21995]: info: [1775903003933] ControllerSpotify::clearAddPlayTrack Apr 11 17:23:23 volumio volumio[21995]: info: Sending Spotify command with payload to local API: /player/play Apr 11 17:23:29 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:29 volumio volumio[21995]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::ClearQueue Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::stop Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:29 volumio volumio[21995]: info: CorePlayQueue::clearPlayQueue Apr 11 17:23:29 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:29 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::addQueueItems Apr 11 17:23:29 volumio volumio[21995]: info: CorePlayQueue::addQueueItems Apr 11 17:23:29 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5AnWrUzsrFgBvhn6dUElnr Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5AnWrUzsrFgBvhn6dUElnr Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0R7grQWU1dRxiOQGIqZm0Z Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:0R7grQWU1dRxiOQGIqZm0Z Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:09WTiAB81RmPHae2BPjtaQ Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:09WTiAB81RmPHae2BPjtaQ Apr 11 17:23:29 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:29 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::updateTrackBlock Apr 11 17:23:29 volumio volumio[21995]: info: CorePlayQueue::getTrackBlock Apr 11 17:23:29 volumio volumio[21995]: info: CoreCommandRouter::volumioPlay Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::play index 2 Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::addQueueItems Apr 11 17:23:29 volumio volumio[21995]: info: CorePlayQueue::addQueueItems Apr 11 17:23:29 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5x72Q1SyiDHJ4IF2mRzfkg Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5x72Q1SyiDHJ4IF2mRzfkg Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2oJxsbdvHuzkQ44hoyMsis Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:2oJxsbdvHuzkQ44hoyMsis Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0eNiqkazTEYJJ8fMRPyJx8 Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:0eNiqkazTEYJJ8fMRPyJx8 Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2nP1wLRh85mo5Wdj4RQldp Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:2nP1wLRh85mo5Wdj4RQldp Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3hsDw4QmMZUtjlB1Wn7snI Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:3hsDw4QmMZUtjlB1Wn7snI Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6YZxrJsE14C7bfyQGFJqqm Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:6YZxrJsE14C7bfyQGFJqqm Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2SN54RFfaafTk7TE1n1Na4 Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:2SN54RFfaafTk7TE1n1Na4 Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3DbNXniRprbs23Vlmy4roM Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:3DbNXniRprbs23Vlmy4roM Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3yxUi5nDZSWcYKi1C2xmLd Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:3yxUi5nDZSWcYKi1C2xmLd Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7ohit6uKKUf6nESaNcXAgp Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:7ohit6uKKUf6nESaNcXAgp Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5icrEfubHCXlHEue9YUT3q Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5icrEfubHCXlHEue9YUT3q Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0qPoQiQIhgyMaP7X78hxri Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:0qPoQiQIhgyMaP7X78hxri Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5I2wbDvSI98HLVB42YO9sU Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5I2wbDvSI98HLVB42YO9sU Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5ZjWYv5kOH1ywFmv5JjPNd Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5ZjWYv5kOH1ywFmv5JjPNd Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6t9KM2EQD4H2KbrTgHGYf3 Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:6t9KM2EQD4H2KbrTgHGYf3 Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6zojSFiUNySBy3Kd1JVCXb Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:6zojSFiUNySBy3Kd1JVCXb Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6EAMI8iHwC3TCSBGVKoWng Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:6EAMI8iHwC3TCSBGVKoWng Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:12YztU3FVYQAio98iwGkVr Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:12YztU3FVYQAio98iwGkVr Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7zXcLSiKnMtmrmRQ0DieS9 Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:7zXcLSiKnMtmrmRQ0DieS9 Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7BdVecQ6n11Qn6m5L9WDBW Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:7BdVecQ6n11Qn6m5L9WDBW Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5cjVsWqIkBQC7acTRhL0RO Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5cjVsWqIkBQC7acTRhL0RO Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5J5Oam2frcy5zQnOCb3Cdr Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5J5Oam2frcy5zQnOCb3Cdr Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:1HBGWNyoRJhxvsfO8LLFDo Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:1HBGWNyoRJhxvsfO8LLFDo Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2ZD4aIEepqZsdxPxLSuUhm Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:2ZD4aIEepqZsdxPxLSuUhm Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0ayku1IzLHuA3cHY4HxOCN Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:0ayku1IzLHuA3cHY4HxOCN Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:1ErMeReQpwGuroEk5zekr3 Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:1ErMeReQpwGuroEk5zekr3 Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2EFi7ey45Za7sxgRZWdmWd Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:2EFi7ey45Za7sxgRZWdmWd Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:4tjLYTXFqZhkUDga4bQ0yl Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:4tjLYTXFqZhkUDga4bQ0yl Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7CY0CmCpSglfUKCBOOiRfw Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:7CY0CmCpSglfUKCBOOiRfw Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2vMLHRfRYMOhPk5eGBzRTQ Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:2vMLHRfRYMOhPk5eGBzRTQ Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:06wmFmUzR15wlWpjTYwVTr Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:06wmFmUzR15wlWpjTYwVTr Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:4dJWik0ax9bRFXl0HqbFjT Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:4dJWik0ax9bRFXl0HqbFjT Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0CtZpaOhtzvLV3FfcsVpQo Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:0CtZpaOhtzvLV3FfcsVpQo Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:1OACsTiYokI4NUgnMonSmt Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:1OACsTiYokI4NUgnMonSmt Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3InIAYQyi22mfV4g1T9Jkc Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:3InIAYQyi22mfV4g1T9Jkc Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2q12vH6qEvg8y1XBCgTbHZ Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:2q12vH6qEvg8y1XBCgTbHZ Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7vnXEUbyvC5VAYLgpstOAQ Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:7vnXEUbyvC5VAYLgpstOAQ Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2FZDNh6tm9mkZ9vTphuAh8 Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:2FZDNh6tm9mkZ9vTphuAh8 Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3F0I9AkbRwz12yz7Hxn0bF Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:3F0I9AkbRwz12yz7Hxn0bF Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2xbvLQBFW7E9232BwMBljb Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:2xbvLQBFW7E9232BwMBljb Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6IR6mzRO60hnMjNoq8rCPE Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:6IR6mzRO60hnMjNoq8rCPE Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5NK3IhIeIXQmOKK5EiSRra Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5NK3IhIeIXQmOKK5EiSRra Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:02ChyNqDE1nEJacyj2i2eQ Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:02ChyNqDE1nEJacyj2i2eQ Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5jAi1HXmSUd5poIEOQkYSy Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5jAi1HXmSUd5poIEOQkYSy Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:068HSvCf5MbQfhV4qqaelg Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:068HSvCf5MbQfhV4qqaelg Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3T0KcbL448JvlX81gazhAd Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:3T0KcbL448JvlX81gazhAd Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3AQrBSjowqCFu2jiFFuGsv Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:3AQrBSjowqCFu2jiFFuGsv Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5SVpD60u9vjoQ2OeoKsTkb Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5SVpD60u9vjoQ2OeoKsTkb Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:67u2VDnIGjc9wYxSaUaUvt Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:67u2VDnIGjc9wYxSaUaUvt Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3x9vShiP4hDhE94zvObYNS Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:3x9vShiP4hDhE94zvObYNS Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2nnDPEbNxnBKq5RG6sObTg Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:2nnDPEbNxnBKq5RG6sObTg Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:4Ywwc43oYbL2zRWWcbSo1c Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:4Ywwc43oYbL2zRWWcbSo1c Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0iFTYXQS4rUhCAcLZcalKq Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:0iFTYXQS4rUhCAcLZcalKq Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5sqPE9h98DkO5ms20NUiUg Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5sqPE9h98DkO5ms20NUiUg Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6cI3I2flP8qvnLLgvrXWg0 Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:6cI3I2flP8qvnLLgvrXWg0 Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3KeMulXbLDJBQdY5PZbGEh Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:3KeMulXbLDJBQdY5PZbGEh Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5jE2mbf6tsHwYIk0tiEix2 Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5jE2mbf6tsHwYIk0tiEix2 Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:16kiQQ4BoLHDyj5W2fkfNK Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:16kiQQ4BoLHDyj5W2fkfNK Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:352wEkChkiClswqwDoM0nj Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:352wEkChkiClswqwDoM0nj Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2PEo8JQ48mfEv8a2HLqDvt Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:2PEo8JQ48mfEv8a2HLqDvt Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6oDHAjaJJjwwgoAuMIeY9K Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:6oDHAjaJJjwwgoAuMIeY9K Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6oloWgrErtc8dG2WiV55gn Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:6oloWgrErtc8dG2WiV55gn Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2UE5pVmMGjfD5XUQTpYpD6 Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:2UE5pVmMGjfD5XUQTpYpD6 Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5XxzNB2CnNrySvyVLwyobq Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:5XxzNB2CnNrySvyVLwyobq Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7E0A86Yhc0ZyOl6aAPE5wN Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:7E0A86Yhc0ZyOl6aAPE5wN Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3ZjMdVOWxQ53FKrmkgCv95 Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:3ZjMdVOWxQ53FKrmkgCv95 Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7y4VovDguP1Wpw5rriHx3E Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:7y4VovDguP1Wpw5rriHx3E Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:4Y6YA8TdR8zZgS1yzMA0rn Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:4Y6YA8TdR8zZgS1yzMA0rn Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6FAYpZ4jve8vpvTwUvjK6H Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:6FAYpZ4jve8vpvTwUvjK6H Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6XvQPMLZdPPV22rY7FEBpI Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:6XvQPMLZdPPV22rY7FEBpI Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0RNMEHUopgrDVk7XE4xRdp Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:0RNMEHUopgrDVk7XE4xRdp Apr 11 17:23:29 volumio volumio[21995]: info: Adding Item to queue: spotify:track:59vbPXCgc6oxbSzMfVBLNb Apr 11 17:23:29 volumio volumio[21995]: info: Using cached record of: spotify:track:59vbPXCgc6oxbSzMfVBLNb Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::stop Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:29 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:29 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::play index undefined Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::updateTrackBlock Apr 11 17:23:29 volumio volumio[21995]: info: CorePlayQueue::getTrackBlock Apr 11 17:23:29 volumio volumio[21995]: info: CorePlayQueue::getTrack 2 Apr 11 17:23:29 volumio volumio[21995]: info: CoreStateMachine::startPlaybackTimer Apr 11 17:23:29 volumio volumio[21995]: info: CorePlayQueue::getTrack 2 Apr 11 17:23:29 volumio volumio[21995]: info: [1775903009701] ControllerSpotify::clearAddPlayTrack Apr 11 17:23:29 volumio volumio[21995]: info: Sending Spotify command with payload to local API: /player/play Apr 11 17:23:32 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:32 volumio volumio[21995]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::ClearQueue Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::stop Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::clearPlayQueue Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:32 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::addQueueItems Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::addQueueItems Apr 11 17:23:32 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5AnWrUzsrFgBvhn6dUElnr Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5AnWrUzsrFgBvhn6dUElnr Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0R7grQWU1dRxiOQGIqZm0Z Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0R7grQWU1dRxiOQGIqZm0Z Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:09WTiAB81RmPHae2BPjtaQ Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:09WTiAB81RmPHae2BPjtaQ Apr 11 17:23:32 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::updateTrackBlock Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::getTrackBlock Apr 11 17:23:32 volumio volumio[21995]: info: CoreCommandRouter::volumioPlay Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::play index 2 Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::addQueueItems Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::addQueueItems Apr 11 17:23:32 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5x72Q1SyiDHJ4IF2mRzfkg Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5x72Q1SyiDHJ4IF2mRzfkg Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2oJxsbdvHuzkQ44hoyMsis Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2oJxsbdvHuzkQ44hoyMsis Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0eNiqkazTEYJJ8fMRPyJx8 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0eNiqkazTEYJJ8fMRPyJx8 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2nP1wLRh85mo5Wdj4RQldp Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2nP1wLRh85mo5Wdj4RQldp Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3hsDw4QmMZUtjlB1Wn7snI Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3hsDw4QmMZUtjlB1Wn7snI Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6YZxrJsE14C7bfyQGFJqqm Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6YZxrJsE14C7bfyQGFJqqm Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2SN54RFfaafTk7TE1n1Na4 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2SN54RFfaafTk7TE1n1Na4 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3DbNXniRprbs23Vlmy4roM Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3DbNXniRprbs23Vlmy4roM Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3yxUi5nDZSWcYKi1C2xmLd Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3yxUi5nDZSWcYKi1C2xmLd Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7ohit6uKKUf6nESaNcXAgp Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7ohit6uKKUf6nESaNcXAgp Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5icrEfubHCXlHEue9YUT3q Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5icrEfubHCXlHEue9YUT3q Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0qPoQiQIhgyMaP7X78hxri Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0qPoQiQIhgyMaP7X78hxri Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5I2wbDvSI98HLVB42YO9sU Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5I2wbDvSI98HLVB42YO9sU Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5ZjWYv5kOH1ywFmv5JjPNd Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5ZjWYv5kOH1ywFmv5JjPNd Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6t9KM2EQD4H2KbrTgHGYf3 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6t9KM2EQD4H2KbrTgHGYf3 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6zojSFiUNySBy3Kd1JVCXb Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6zojSFiUNySBy3Kd1JVCXb Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6EAMI8iHwC3TCSBGVKoWng Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6EAMI8iHwC3TCSBGVKoWng Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:12YztU3FVYQAio98iwGkVr Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:12YztU3FVYQAio98iwGkVr Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7zXcLSiKnMtmrmRQ0DieS9 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7zXcLSiKnMtmrmRQ0DieS9 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7BdVecQ6n11Qn6m5L9WDBW Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7BdVecQ6n11Qn6m5L9WDBW Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5cjVsWqIkBQC7acTRhL0RO Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5cjVsWqIkBQC7acTRhL0RO Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5J5Oam2frcy5zQnOCb3Cdr Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5J5Oam2frcy5zQnOCb3Cdr Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:1HBGWNyoRJhxvsfO8LLFDo Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:1HBGWNyoRJhxvsfO8LLFDo Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2ZD4aIEepqZsdxPxLSuUhm Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2ZD4aIEepqZsdxPxLSuUhm Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0ayku1IzLHuA3cHY4HxOCN Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0ayku1IzLHuA3cHY4HxOCN Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:1ErMeReQpwGuroEk5zekr3 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:1ErMeReQpwGuroEk5zekr3 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2EFi7ey45Za7sxgRZWdmWd Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2EFi7ey45Za7sxgRZWdmWd Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:4tjLYTXFqZhkUDga4bQ0yl Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:4tjLYTXFqZhkUDga4bQ0yl Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7CY0CmCpSglfUKCBOOiRfw Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7CY0CmCpSglfUKCBOOiRfw Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2vMLHRfRYMOhPk5eGBzRTQ Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2vMLHRfRYMOhPk5eGBzRTQ Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:06wmFmUzR15wlWpjTYwVTr Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:06wmFmUzR15wlWpjTYwVTr Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:4dJWik0ax9bRFXl0HqbFjT Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:4dJWik0ax9bRFXl0HqbFjT Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0CtZpaOhtzvLV3FfcsVpQo Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0CtZpaOhtzvLV3FfcsVpQo Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:1OACsTiYokI4NUgnMonSmt Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:1OACsTiYokI4NUgnMonSmt Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3InIAYQyi22mfV4g1T9Jkc Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3InIAYQyi22mfV4g1T9Jkc Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2q12vH6qEvg8y1XBCgTbHZ Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2q12vH6qEvg8y1XBCgTbHZ Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7vnXEUbyvC5VAYLgpstOAQ Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7vnXEUbyvC5VAYLgpstOAQ Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2FZDNh6tm9mkZ9vTphuAh8 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2FZDNh6tm9mkZ9vTphuAh8 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3F0I9AkbRwz12yz7Hxn0bF Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3F0I9AkbRwz12yz7Hxn0bF Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2xbvLQBFW7E9232BwMBljb Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2xbvLQBFW7E9232BwMBljb Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6IR6mzRO60hnMjNoq8rCPE Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6IR6mzRO60hnMjNoq8rCPE Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5NK3IhIeIXQmOKK5EiSRra Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5NK3IhIeIXQmOKK5EiSRra Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:02ChyNqDE1nEJacyj2i2eQ Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:02ChyNqDE1nEJacyj2i2eQ Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5jAi1HXmSUd5poIEOQkYSy Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5jAi1HXmSUd5poIEOQkYSy Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:068HSvCf5MbQfhV4qqaelg Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:068HSvCf5MbQfhV4qqaelg Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3T0KcbL448JvlX81gazhAd Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3T0KcbL448JvlX81gazhAd Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3AQrBSjowqCFu2jiFFuGsv Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3AQrBSjowqCFu2jiFFuGsv Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5SVpD60u9vjoQ2OeoKsTkb Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5SVpD60u9vjoQ2OeoKsTkb Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:67u2VDnIGjc9wYxSaUaUvt Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:67u2VDnIGjc9wYxSaUaUvt Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3x9vShiP4hDhE94zvObYNS Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3x9vShiP4hDhE94zvObYNS Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2nnDPEbNxnBKq5RG6sObTg Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2nnDPEbNxnBKq5RG6sObTg Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:4Ywwc43oYbL2zRWWcbSo1c Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:4Ywwc43oYbL2zRWWcbSo1c Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0iFTYXQS4rUhCAcLZcalKq Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0iFTYXQS4rUhCAcLZcalKq Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5sqPE9h98DkO5ms20NUiUg Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5sqPE9h98DkO5ms20NUiUg Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6cI3I2flP8qvnLLgvrXWg0 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6cI3I2flP8qvnLLgvrXWg0 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3KeMulXbLDJBQdY5PZbGEh Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3KeMulXbLDJBQdY5PZbGEh Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5jE2mbf6tsHwYIk0tiEix2 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5jE2mbf6tsHwYIk0tiEix2 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:16kiQQ4BoLHDyj5W2fkfNK Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:16kiQQ4BoLHDyj5W2fkfNK Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:352wEkChkiClswqwDoM0nj Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:352wEkChkiClswqwDoM0nj Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2PEo8JQ48mfEv8a2HLqDvt Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2PEo8JQ48mfEv8a2HLqDvt Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6oDHAjaJJjwwgoAuMIeY9K Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6oDHAjaJJjwwgoAuMIeY9K Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6oloWgrErtc8dG2WiV55gn Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6oloWgrErtc8dG2WiV55gn Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2UE5pVmMGjfD5XUQTpYpD6 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2UE5pVmMGjfD5XUQTpYpD6 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5XxzNB2CnNrySvyVLwyobq Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5XxzNB2CnNrySvyVLwyobq Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7E0A86Yhc0ZyOl6aAPE5wN Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7E0A86Yhc0ZyOl6aAPE5wN Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3ZjMdVOWxQ53FKrmkgCv95 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3ZjMdVOWxQ53FKrmkgCv95 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7y4VovDguP1Wpw5rriHx3E Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7y4VovDguP1Wpw5rriHx3E Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:4Y6YA8TdR8zZgS1yzMA0rn Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:4Y6YA8TdR8zZgS1yzMA0rn Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6FAYpZ4jve8vpvTwUvjK6H Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6FAYpZ4jve8vpvTwUvjK6H Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6XvQPMLZdPPV22rY7FEBpI Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6XvQPMLZdPPV22rY7FEBpI Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0RNMEHUopgrDVk7XE4xRdp Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0RNMEHUopgrDVk7XE4xRdp Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:59vbPXCgc6oxbSzMfVBLNb Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:59vbPXCgc6oxbSzMfVBLNb Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::stop Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:32 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::play index undefined Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::updateTrackBlock Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::getTrackBlock Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::getTrack 2 Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::startPlaybackTimer Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::getTrack 2 Apr 11 17:23:32 volumio volumio[21995]: info: [1775903012517] ControllerSpotify::clearAddPlayTrack Apr 11 17:23:32 volumio volumio[21995]: info: Sending Spotify command with payload to local API: /player/play Apr 11 17:23:32 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:32 volumio volumio[21995]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::ClearQueue Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::stop Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::clearPlayQueue Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:32 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::addQueueItems Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::addQueueItems Apr 11 17:23:32 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5AnWrUzsrFgBvhn6dUElnr Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5AnWrUzsrFgBvhn6dUElnr Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0R7grQWU1dRxiOQGIqZm0Z Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0R7grQWU1dRxiOQGIqZm0Z Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:09WTiAB81RmPHae2BPjtaQ Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:09WTiAB81RmPHae2BPjtaQ Apr 11 17:23:32 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:32 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::updateTrackBlock Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::getTrackBlock Apr 11 17:23:32 volumio volumio[21995]: info: CoreCommandRouter::volumioPlay Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::play index 2 Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::addQueueItems Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::addQueueItems Apr 11 17:23:32 volumio volumio[21995]: info: Preload queue cleared Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5x72Q1SyiDHJ4IF2mRzfkg Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5x72Q1SyiDHJ4IF2mRzfkg Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2oJxsbdvHuzkQ44hoyMsis Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2oJxsbdvHuzkQ44hoyMsis Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0eNiqkazTEYJJ8fMRPyJx8 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0eNiqkazTEYJJ8fMRPyJx8 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2nP1wLRh85mo5Wdj4RQldp Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2nP1wLRh85mo5Wdj4RQldp Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3hsDw4QmMZUtjlB1Wn7snI Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3hsDw4QmMZUtjlB1Wn7snI Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6YZxrJsE14C7bfyQGFJqqm Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6YZxrJsE14C7bfyQGFJqqm Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2SN54RFfaafTk7TE1n1Na4 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2SN54RFfaafTk7TE1n1Na4 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3DbNXniRprbs23Vlmy4roM Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3DbNXniRprbs23Vlmy4roM Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3yxUi5nDZSWcYKi1C2xmLd Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3yxUi5nDZSWcYKi1C2xmLd Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7ohit6uKKUf6nESaNcXAgp Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7ohit6uKKUf6nESaNcXAgp Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5icrEfubHCXlHEue9YUT3q Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5icrEfubHCXlHEue9YUT3q Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0qPoQiQIhgyMaP7X78hxri Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0qPoQiQIhgyMaP7X78hxri Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5I2wbDvSI98HLVB42YO9sU Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5I2wbDvSI98HLVB42YO9sU Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5ZjWYv5kOH1ywFmv5JjPNd Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5ZjWYv5kOH1ywFmv5JjPNd Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6t9KM2EQD4H2KbrTgHGYf3 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6t9KM2EQD4H2KbrTgHGYf3 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6zojSFiUNySBy3Kd1JVCXb Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6zojSFiUNySBy3Kd1JVCXb Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6EAMI8iHwC3TCSBGVKoWng Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6EAMI8iHwC3TCSBGVKoWng Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:12YztU3FVYQAio98iwGkVr Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:12YztU3FVYQAio98iwGkVr Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7zXcLSiKnMtmrmRQ0DieS9 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7zXcLSiKnMtmrmRQ0DieS9 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7BdVecQ6n11Qn6m5L9WDBW Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7BdVecQ6n11Qn6m5L9WDBW Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5cjVsWqIkBQC7acTRhL0RO Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5cjVsWqIkBQC7acTRhL0RO Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5J5Oam2frcy5zQnOCb3Cdr Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5J5Oam2frcy5zQnOCb3Cdr Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:1HBGWNyoRJhxvsfO8LLFDo Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:1HBGWNyoRJhxvsfO8LLFDo Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2ZD4aIEepqZsdxPxLSuUhm Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2ZD4aIEepqZsdxPxLSuUhm Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0ayku1IzLHuA3cHY4HxOCN Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0ayku1IzLHuA3cHY4HxOCN Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:1ErMeReQpwGuroEk5zekr3 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:1ErMeReQpwGuroEk5zekr3 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2EFi7ey45Za7sxgRZWdmWd Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2EFi7ey45Za7sxgRZWdmWd Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:4tjLYTXFqZhkUDga4bQ0yl Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:4tjLYTXFqZhkUDga4bQ0yl Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7CY0CmCpSglfUKCBOOiRfw Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7CY0CmCpSglfUKCBOOiRfw Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2vMLHRfRYMOhPk5eGBzRTQ Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2vMLHRfRYMOhPk5eGBzRTQ Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:06wmFmUzR15wlWpjTYwVTr Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:06wmFmUzR15wlWpjTYwVTr Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:4dJWik0ax9bRFXl0HqbFjT Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:4dJWik0ax9bRFXl0HqbFjT Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0CtZpaOhtzvLV3FfcsVpQo Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0CtZpaOhtzvLV3FfcsVpQo Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:1OACsTiYokI4NUgnMonSmt Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:1OACsTiYokI4NUgnMonSmt Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3InIAYQyi22mfV4g1T9Jkc Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3InIAYQyi22mfV4g1T9Jkc Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2q12vH6qEvg8y1XBCgTbHZ Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2q12vH6qEvg8y1XBCgTbHZ Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7vnXEUbyvC5VAYLgpstOAQ Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7vnXEUbyvC5VAYLgpstOAQ Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2FZDNh6tm9mkZ9vTphuAh8 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2FZDNh6tm9mkZ9vTphuAh8 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3F0I9AkbRwz12yz7Hxn0bF Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3F0I9AkbRwz12yz7Hxn0bF Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2xbvLQBFW7E9232BwMBljb Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2xbvLQBFW7E9232BwMBljb Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6IR6mzRO60hnMjNoq8rCPE Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6IR6mzRO60hnMjNoq8rCPE Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5NK3IhIeIXQmOKK5EiSRra Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5NK3IhIeIXQmOKK5EiSRra Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:02ChyNqDE1nEJacyj2i2eQ Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:02ChyNqDE1nEJacyj2i2eQ Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5jAi1HXmSUd5poIEOQkYSy Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5jAi1HXmSUd5poIEOQkYSy Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:068HSvCf5MbQfhV4qqaelg Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:068HSvCf5MbQfhV4qqaelg Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3T0KcbL448JvlX81gazhAd Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3T0KcbL448JvlX81gazhAd Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3AQrBSjowqCFu2jiFFuGsv Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3AQrBSjowqCFu2jiFFuGsv Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5SVpD60u9vjoQ2OeoKsTkb Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5SVpD60u9vjoQ2OeoKsTkb Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:67u2VDnIGjc9wYxSaUaUvt Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:67u2VDnIGjc9wYxSaUaUvt Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3x9vShiP4hDhE94zvObYNS Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3x9vShiP4hDhE94zvObYNS Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2nnDPEbNxnBKq5RG6sObTg Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2nnDPEbNxnBKq5RG6sObTg Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:4Ywwc43oYbL2zRWWcbSo1c Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:4Ywwc43oYbL2zRWWcbSo1c Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0iFTYXQS4rUhCAcLZcalKq Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0iFTYXQS4rUhCAcLZcalKq Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5sqPE9h98DkO5ms20NUiUg Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5sqPE9h98DkO5ms20NUiUg Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6cI3I2flP8qvnLLgvrXWg0 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6cI3I2flP8qvnLLgvrXWg0 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3KeMulXbLDJBQdY5PZbGEh Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3KeMulXbLDJBQdY5PZbGEh Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5jE2mbf6tsHwYIk0tiEix2 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5jE2mbf6tsHwYIk0tiEix2 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:16kiQQ4BoLHDyj5W2fkfNK Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:16kiQQ4BoLHDyj5W2fkfNK Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:352wEkChkiClswqwDoM0nj Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:352wEkChkiClswqwDoM0nj Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2PEo8JQ48mfEv8a2HLqDvt Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2PEo8JQ48mfEv8a2HLqDvt Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6oDHAjaJJjwwgoAuMIeY9K Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6oDHAjaJJjwwgoAuMIeY9K Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6oloWgrErtc8dG2WiV55gn Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6oloWgrErtc8dG2WiV55gn Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:2UE5pVmMGjfD5XUQTpYpD6 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:2UE5pVmMGjfD5XUQTpYpD6 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:5XxzNB2CnNrySvyVLwyobq Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:5XxzNB2CnNrySvyVLwyobq Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7E0A86Yhc0ZyOl6aAPE5wN Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7E0A86Yhc0ZyOl6aAPE5wN Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:3ZjMdVOWxQ53FKrmkgCv95 Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:3ZjMdVOWxQ53FKrmkgCv95 Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:7y4VovDguP1Wpw5rriHx3E Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:7y4VovDguP1Wpw5rriHx3E Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:4Y6YA8TdR8zZgS1yzMA0rn Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:4Y6YA8TdR8zZgS1yzMA0rn Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6FAYpZ4jve8vpvTwUvjK6H Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6FAYpZ4jve8vpvTwUvjK6H Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:6XvQPMLZdPPV22rY7FEBpI Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:6XvQPMLZdPPV22rY7FEBpI Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:0RNMEHUopgrDVk7XE4xRdp Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:0RNMEHUopgrDVk7XE4xRdp Apr 11 17:23:32 volumio volumio[21995]: info: Adding Item to queue: spotify:track:59vbPXCgc6oxbSzMfVBLNb Apr 11 17:23:32 volumio volumio[21995]: info: Using cached record of: spotify:track:59vbPXCgc6oxbSzMfVBLNb Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::stop Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:32 volumio volumio[21995]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::saveQueue Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::play index undefined Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::updateTrackBlock Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::getTrackBlock Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::getTrack 2 Apr 11 17:23:32 volumio volumio[21995]: info: CoreStateMachine::startPlaybackTimer Apr 11 17:23:32 volumio volumio[21995]: info: CorePlayQueue::getTrack 2 Apr 11 17:23:32 volumio volumio[21995]: info: [1775903012727] ControllerSpotify::clearAddPlayTrack Apr 11 17:23:32 volumio volumio[21995]: info: Sending Spotify command with payload to local API: /player/play Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:23:38 volumio volumio[21995]: info: Retrieving Cloud Streaming UI Apr 11 17:23:38 volumio volumio[21995]: info: Getting Tidal Cloud Configuration Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:23:38 volumio volumio[21995]: info: Getting Qobuz Cloud Configuration Apr 11 17:23:38 volumio volumio[21995]: info: Asking plugin for UI Config Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:23:38 volumio volumio[21995]: info: Getting Spotify Cloud Configuration Apr 11 17:23:38 volumio volumio[21995]: info: Asking plugin for UI Config Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:23:38 volumio volumio[21995]: info: Saving Spotify Acccount Apr 11 17:23:38 volumio volumio[21995]: info: Got Tidal Cloud Configuration Apr 11 17:23:38 volumio volumio[21995]: info: Got it Apr 11 17:23:38 volumio volumio[21995]: info: Got it Apr 11 17:23:38 volumio volumio[21995]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:23:38 volumio volumio[21995]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::volumioGetBrowseSources Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::volumioGetBrowseSources Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::volumioGetBrowseSources Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 17:23:38 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 11 17:23:42 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 17:23:46 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 17:23:50 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 17:23:54 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 17:23:58 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 17:24:02 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 17:24:03 volumio volumio[21995]: info: Enabling MyMusic plugin bluetooth Apr 11 17:24:03 volumio volumio[21995]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Apr 11 17:24:03 volumio volumio[21995]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Apr 11 17:24:03 volumio volumio[21995]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Apr 11 17:24:03 volumio volumio[21995]: error: Could not Enable MyMusic Plugin: Error Apr 11 17:24:06 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 17:24:07 volumio wpa_supplicant[3915]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 11 17:24:10 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 17:24:11 volumio wpa_supplicant[3915]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 11 17:24:13 volumio wpa_supplicant[3915]: wlan0: CTRL-EVENT-BEACON-LOSS Apr 11 17:24:14 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 17:24:18 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 17:24:24 volumio volumio[21995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:24:24 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 11 17:24:24 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 11 17:24:24 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 11 17:24:24 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 11 17:24:24 volumio volumio[21995]: info: Received Get System Version Apr 11 17:24:24 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 17:24:24 volumio volumio[21995]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Apr 11 17:24:25 volumio go-librespot[22197]: time="2026-04-11T17:24:25+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Apr 11 17:24:25 volumio go-librespot[22197]: time="2026-04-11T17:24:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Apr 11 17:24:25 volumio go-librespot[22197]: time="2026-04-11T17:24:25+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:25 volumio go-librespot[22197]: time="2026-04-11T17:24:25+07:00" level=debug msg="completed challenge" Apr 11 17:24:25 volumio go-librespot[22197]: time="2026-04-11T17:24:25+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:26 volumio go-librespot[22197]: time="2026-04-11T17:24:26+07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Apr 11 17:24:26 volumio go-librespot[22197]: time="2026-04-11T17:24:26+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:26 volumio go-librespot[22197]: time="2026-04-11T17:24:26+07:00" level=debug msg="completed challenge" Apr 11 17:24:26 volumio go-librespot[22197]: time="2026-04-11T17:24:26+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:27 volumio go-librespot[22197]: time="2026-04-11T17:24:27+07:00" level=debug msg="connected to ap-gew1.spotify.com:443" Apr 11 17:24:28 volumio go-librespot[22197]: time="2026-04-11T17:24:28+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:28 volumio go-librespot[22197]: time="2026-04-11T17:24:28+07:00" level=debug msg="completed challenge" Apr 11 17:24:28 volumio go-librespot[22197]: time="2026-04-11T17:24:28+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:29 volumio go-librespot[22197]: time="2026-04-11T17:24:29+07:00" level=debug msg="connected to ap-gew4.spotify.com:80" Apr 11 17:24:29 volumio go-librespot[22197]: time="2026-04-11T17:24:29+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:29 volumio go-librespot[22197]: time="2026-04-11T17:24:29+07:00" level=debug msg="completed challenge" Apr 11 17:24:29 volumio go-librespot[22197]: time="2026-04-11T17:24:29+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:29 volumio go-librespot[22197]: time="2026-04-11T17:24:29+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 11 17:24:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 11 17:24:29 volumio volumio[21995]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Apr 11 17:24:29 volumio volumio[21995]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Apr 11 17:24:29 volumio volumio[21995]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Apr 11 17:24:29 volumio volumio[21995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 17:24:29 volumio volumio[21995]: Error: socket hang up Apr 11 17:24:29 volumio volumio[21995]: at connResetException (node:internal/errors:720:14) Apr 11 17:24:29 volumio volumio[21995]: at Socket.socketOnEnd (node:_http_client:519:23) Apr 11 17:24:29 volumio volumio[21995]: at Socket.emit (node:events:526:35) Apr 11 17:24:29 volumio volumio[21995]: at endReadableNT (node:internal/streams/readable:1376:12) Apr 11 17:24:29 volumio volumio[21995]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Apr 11 17:24:29 volumio volumio[21995]: code: 'ECONNRESET', Apr 11 17:24:29 volumio volumio[21995]: response: undefined Apr 11 17:24:29 volumio volumio[21995]: } Apr 11 17:24:29 volumio volumio[21995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 17:24:29 volumio sudo[23976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 17:23' Apr 11 17:24:29 volumio sudo[23976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:29 volumio sudo[23976]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:31 volumio volumio-remote-updater[994]: [2026-04-11 17:24:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 11 17:24:31 volumio volumio-remote-updater[994]: [2026-04-11 17:24:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 11 17:24:31 volumio volumio5-onboarding[13554]: time=2026-04-11T17:24:31.092+07:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Apr 11 17:24:31 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 11 17:24:31 volumio volumio5-onboarding[13554]: time=2026-04-11T17:24:31.093+07:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Apr 11 17:24:31 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 11 17:24:31 volumio systemd[1]: volumio.service: Consumed 24.365s CPU time. Apr 11 17:24:31 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 11 17:24:31 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 11 17:24:31 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 32. Apr 11 17:24:31 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 11 17:24:31 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 11 17:24:31 volumio systemd[1]: volumio.service: Consumed 24.365s CPU time. Apr 11 17:24:31 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 11 17:24:31 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 11 17:24:31 volumio volumio[24036]: info: ------------------------------------------- Apr 11 17:24:31 volumio volumio[24036]: info: ----- Volumio3 ---- Apr 11 17:24:31 volumio volumio[24036]: info: ------------------------------------------- Apr 11 17:24:31 volumio volumio[24036]: info: ----- System startup ---- Apr 11 17:24:31 volumio volumio[24036]: info: ------------------------------------------- Apr 11 17:24:31 volumio volumio[24036]: info: MYVOLUMIO Environment detected Apr 11 17:24:31 volumio volumio[24036]: info: Plugin folders cleanup Apr 11 17:24:31 volumio volumio[24036]: info: Scanning into folder /volumio/app/plugins/ Apr 11 17:24:31 volumio volumio[24036]: info: Scanning category audio_interface Apr 11 17:24:31 volumio volumio[24036]: info: Scanning category miscellanea Apr 11 17:24:31 volumio volumio[24036]: info: Scanning category music_service Apr 11 17:24:31 volumio volumio[24036]: info: Scanning category plugins.json Apr 11 17:24:31 volumio volumio[24036]: info: Scanning category system_controller Apr 11 17:24:31 volumio volumio[24036]: info: Scanning category user_interface Apr 11 17:24:31 volumio volumio[24036]: info: Scanning into folder /data/plugins/ Apr 11 17:24:31 volumio volumio[24036]: info: Scanning category music_service Apr 11 17:24:31 volumio volumio[24036]: info: Plugin folders cleanup completed Apr 11 17:24:31 volumio volumio[24036]: info: ------------------------------------------- Apr 11 17:24:31 volumio volumio[24036]: info: ----- Core plugins startup ---- Apr 11 17:24:31 volumio volumio[24036]: info: ------------------------------------------- Apr 11 17:24:31 volumio volumio[24036]: info: Loading plugins from folder /volumio/app/plugins/ Apr 11 17:24:31 volumio volumio[24036]: info: Adding plugin upnp to MyMusic Plugins Apr 11 17:24:31 volumio volumio[24036]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 11 17:24:31 volumio volumio[24036]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 11 17:24:31 volumio volumio[24036]: info: Loading plugins from folder /data/plugins/ Apr 11 17:24:31 volumio volumio[24036]: info: Loading plugin "system"... Apr 11 17:24:31 volumio volumio[24036]: info: Loading plugin "appearance"... Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "network"... Apr 11 17:24:32 volumio volumio[24036]: info: Refreshing Cached IP Addresses Apr 11 17:24:32 volumio sudo[24066]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 17:24:32 volumio sudo[24066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:32 volumio sudo[24068]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 17:24:32 volumio sudo[24068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:32 volumio sudo[24066]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:32 volumio sudo[24068]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "services"... Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "volumio5onboarding"... Apr 11 17:24:32 volumio sudo[24085]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 11 17:24:32 volumio sudo[24085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "alsa_controller"... Apr 11 17:24:32 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "wizard"... Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "networkfs"... Apr 11 17:24:32 volumio volumio[24036]: info: Starting Udev Watcher for removable devices Apr 11 17:24:32 volumio volumio[24036]: info: Ignoring mount for partition: boot Apr 11 17:24:32 volumio volumio[24036]: info: Ignoring mount for partition: volumio Apr 11 17:24:32 volumio volumio[24036]: info: Ignoring mount for partition: volumio_data Apr 11 17:24:32 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "volumio_command_line_client"... Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "upnp"... Apr 11 17:24:32 volumio volumio[24036]: info: [1775903072201] Starting Upmpd Daemon Apr 11 17:24:32 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "my_music"... Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "mpd"... Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "upnp_browser"... Apr 11 17:24:32 volumio volumio[24036]: info: Starting UPNP Browser Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "alarm-clock"... Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "airplay_emulation"... Apr 11 17:24:32 volumio volumio[24036]: info: Starting Shairport Sync Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "last_100"... Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "webradio"... Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "i2s_dacs"... Apr 11 17:24:32 volumio volumio[24036]: info: I2S DAC not set, start Auto-detection Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "volumiodiscovery"... Apr 11 17:24:32 volumio volumio[24036]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 17:24:32 volumio volumio[24036]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 17:24:32 volumio volumio[24036]: *** WARNING *** For more information see Apr 11 17:24:32 volumio volumio[24036]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 17:24:32 volumio volumio[24036]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 17:24:32 volumio volumio[24036]: *** WARNING *** For more information see Apr 11 17:24:32 volumio node[24036]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 17:24:32 volumio node[24036]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 17:24:32 volumio node[24036]: *** WARNING *** For more information see Apr 11 17:24:32 volumio node[24036]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 17:24:32 volumio node[24036]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 17:24:32 volumio node[24036]: *** WARNING *** For more information see Apr 11 17:24:32 volumio volumio[24036]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 11 17:24:32 volumio volumio[24036]: info: Discovery: Started advertising with name: Volumio Apr 11 17:24:32 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "spop"... Apr 11 17:24:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 11 17:24:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 17:24:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 11 17:24:32 volumio go-librespot[24102]: go-librespot daemon starting... Apr 11 17:24:32 volumio go-librespot[24103]: time="2026-04-11T17:24:32+07:00" level=info msg="running go-librespot 0.7.1" Apr 11 17:24:32 volumio go-librespot[24103]: time="2026-04-11T17:24:32+07:00" level=debug msg="app state loaded" Apr 11 17:24:32 volumio go-librespot[24103]: time="2026-04-11T17:24:32+07:00" level=debug msg="stored credentials not found" Apr 11 17:24:32 volumio go-librespot[24103]: time="2026-04-11T17:24:32+07:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 11 17:24:32 volumio volumio[24036]: info: Loading plugin "youtube2"... Apr 11 17:24:33 volumio volumio[24036]: info: Loading plugin "ytmusic"... Apr 11 17:24:33 volumio volumio[24036]: info: Loading plugin "outputs"... Apr 11 17:24:33 volumio volumio[24036]: info: Loading plugin "albumart"... Apr 11 17:24:33 volumio volumio[24036]: info: Plugin example_plugin is not enabled Apr 11 17:24:33 volumio volumio[24036]: info: Loading plugin "inputs"... Apr 11 17:24:33 volumio volumio[24036]: info: Loading plugin "updater_comm"... Apr 11 17:24:33 volumio volumio[24036]: info: Plugin mpdemulation is not enabled Apr 11 17:24:33 volumio volumio[24036]: info: Loading plugin "rest_api"... Apr 11 17:24:33 volumio volumio[24036]: info: Loading plugin "websocket"... Apr 11 17:24:33 volumio volumio[24036]: info: Starting Socket.io Server version 1.7.4 Apr 11 17:24:33 volumio volumio[24036]: info: Loading plugin "RoonBridge"... Apr 11 17:24:33 volumio volumio[24111]: Forking 3 albumart workers Apr 11 17:24:33 volumio volumio[24036]: info: Applying required configuration parameters for plugin RoonBridge Apr 11 17:24:33 volumio volumio[24036]: info: Loading i18n strings for locale vi Apr 11 17:24:33 volumio volumio[24036]: Updating browse sources language Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::initPlayerControls Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: Express server listening on port 3000 Apr 11 17:24:33 volumio volumio[24036]: [Metrics] WebUI: 1s 850.82ms Apr 11 17:24:33 volumio volumio[24036]: info: Setting Device type: x86 Apr 11 17:24:33 volumio volumio[24036]: info: CoreStateMachine::resetVolumioState Apr 11 17:24:33 volumio volumio[24036]: info: CoreStateMachine::getcurrentVolume Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 11 17:24:33 volumio volumio[24036]: info: Completed loading Core Plugins Apr 11 17:24:33 volumio volumio[24036]: info: Preparing to generate the ALSA configuration file Apr 11 17:24:33 volumio volumio[24121]: Starting albumart workers Apr 11 17:24:33 volumio volumio[24036]: info: Volumio Network Manager: Network status updated: 2 Apr 11 17:24:33 volumio volumio[24123]: Starting albumart workers Apr 11 17:24:33 volumio volumio[24036]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 11 17:24:33 volumio volumio[24036]: verbose: New Socket.io Connection to 192.168.1.24 from 192.168.1.22 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 11 17:24:33 volumio volumio[24036]: Unable to parse: Apr 11 17:24:33 volumio volumio[24036]: Simple mixer control 'IEC958',0 Apr 11 17:24:33 volumio volumio[24036]: Capabilities: pswitch pswitch-joined Apr 11 17:24:33 volumio volumio[24036]: Playback channels: Mono Apr 11 17:24:33 volumio volumio[24036]: Mono: Playback [on] Apr 11 17:24:33 volumio volumio[24036]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 11 17:24:33 volumio volumio[24122]: Starting albumart workers Apr 11 17:24:33 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Apr 11 17:24:33 volumio volumio[24036]: info: Reloading queue from file Apr 11 17:24:33 volumio volumio[24036]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 11 17:24:33 volumio volumio[24036]: Unable to parse: Apr 11 17:24:33 volumio volumio[24036]: Simple mixer control 'IEC958',0 Apr 11 17:24:33 volumio volumio[24036]: Capabilities: pswitch pswitch-joined Apr 11 17:24:33 volumio volumio[24036]: Playback channels: Mono Apr 11 17:24:33 volumio volumio[24036]: Mono: Playback [on] Apr 11 17:24:33 volumio volumio[24036]: info: VolumeController:: Volume=undefined Mute =false Apr 11 17:24:33 volumio volumio[24036]: info: CoreStateMachine::pushState Apr 11 17:24:33 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::volumioPushState Apr 11 17:24:33 volumio volumio[24036]: info: CoreStateMachine::updateTrackBlock Apr 11 17:24:33 volumio volumio[24036]: info: CorePlayQueue::getTrackBlock Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:24:33 volumio volumio[24036]: info: Asound.conf file unchanged, so no further update is needed Apr 11 17:24:33 volumio volumio[24036]: info: Output device has changed, restarting MPD Apr 11 17:24:33 volumio volumio[24036]: info: Output device has changed, restarting Shairport Sync Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:33 volumio sudo[24174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 17:24:33 volumio sudo[24174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:33 volumio sudo[24174]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:33 volumio sudo[24173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 17:24:33 volumio sudo[24173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:33 volumio volumio[24036]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:24:33 volumio volumio[24036]: info: ___________ START PLUGINS ___________ Apr 11 17:24:33 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 11 17:24:33 volumio volumio[24036]: info: ControllerMpd::onStart: Initializing MPD Apr 11 17:24:33 volumio volumio[24036]: info: Creating MPD Configuration file Apr 11 17:24:33 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 11 17:24:33 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 17:24:33 volumio sudo[24182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 11 17:24:33 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 11 17:24:33 volumio sudo[24182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:33 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 17:24:33 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 17:24:33 volumio sudo[24184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 17:24:33 volumio sudo[24184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 17:24:33 volumio volumio[24036]: info: [1775903073768] CoreMusicLibrary::Adding element Máy chủ Media Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:33 volumio sudo[24184]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:33 volumio sudo[24189]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 17:24:33 volumio sudo[24189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:33 volumio volumio[24036]: info: UPNP Browser: Client initialized successfully Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 17:24:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 17:24:33 volumio volumio[24036]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:33 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 11 17:24:33 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 17:24:33 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 11 17:24:33 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 17:24:33 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 17:24:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 17:24:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 17:24:33 volumio sudo[24182]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:33 volumio volumio[24036]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 17:24:33 volumio volumio[24036]: info: [1775903073822] CoreMusicLibrary::Adding element Last_100 Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 17:24:33 volumio volumio[24036]: info: [1775903073823] CoreMusicLibrary::Adding element Webradio Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 17:24:33 volumio volumio[24036]: info: Initializing BBC Radios Apr 11 17:24:33 volumio sudo[24201]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 11 17:24:33 volumio sudo[24201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 17:24:33 volumio sudo[24201]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: Creating Spotify config file Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 17:24:33 volumio volumio[24036]: info: [1775903073933] CoreMusicLibrary::Adding element YouTube2 Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:33 volumio volumio[24036]: Cannot find translation for source YouTube2 Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 17:24:33 volumio volumio[24036]: info: [1775903073936] CoreMusicLibrary::Adding element YouTube Music Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:33 volumio volumio[24036]: Cannot find translation for source YouTube2 Apr 11 17:24:33 volumio volumio[24036]: Cannot find translation for source YouTube Music Apr 11 17:24:33 volumio volumio[24036]: info: Volumio Calling Home Apr 11 17:24:33 volumio sudo[24224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Apr 11 17:24:33 volumio sudo[24224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:33 volumio sudo[24224]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:33 volumio volumio[24036]: info: CoreStateMachine::setRepeat null single undefined Apr 11 17:24:33 volumio volumio[24036]: info: CoreStateMachine::pushState Apr 11 17:24:33 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::volumioPushState Apr 11 17:24:33 volumio volumio[24036]: info: CoreStateMachine::setRandom null Apr 11 17:24:33 volumio volumio[24036]: info: CoreStateMachine::pushState Apr 11 17:24:33 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:33 volumio volumio[24036]: info: CoreCommandRouter::volumioPushState Apr 11 17:24:33 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Apr 11 17:24:33 volumio volumio[24036]: info: MPD Permissions set Apr 11 17:24:33 volumio volumio[24036]: info: MPD Permissions set Apr 11 17:24:33 volumio volumio[24036]: Unable to parse: Apr 11 17:24:33 volumio volumio[24036]: Simple mixer control 'IEC958',0 Apr 11 17:24:33 volumio volumio[24036]: Capabilities: pswitch pswitch-joined Apr 11 17:24:33 volumio volumio[24036]: Playback channels: Mono Apr 11 17:24:33 volumio volumio[24036]: Mono: Playback [on] Apr 11 17:24:33 volumio volumio[24036]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 11 17:24:34 volumio volumio[24036]: error: Upnp client error: Error: This socket has been ended by the other party Apr 11 17:24:34 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 11 17:24:34 volumio volumio[24036]: info: Spotify config file written Apr 11 17:24:34 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Apr 11 17:24:34 volumio sudo[24233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 17:24:34 volumio sudo[24233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:34 volumio volumio[24036]: Unable to parse: Apr 11 17:24:34 volumio volumio[24036]: Simple mixer control 'IEC958',0 Apr 11 17:24:34 volumio volumio[24036]: Capabilities: pswitch pswitch-joined Apr 11 17:24:34 volumio volumio[24036]: Playback channels: Mono Apr 11 17:24:34 volumio volumio[24036]: Mono: Playback [on] Apr 11 17:24:34 volumio volumio[24036]: info: VolumeController:: Volume=undefined Mute =false Apr 11 17:24:34 volumio volumio[24036]: info: CoreStateMachine::pushState Apr 11 17:24:34 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::volumioPushState Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 11 17:24:34 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 11 17:24:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 11 17:24:34 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:34 volumio volumio[24036]: info: No need to fix Spotify hosts Apr 11 17:24:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 11 17:24:34 volumio go-librespot[24240]: go-librespot daemon starting... Apr 11 17:24:34 volumio sudo[24233]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:34 volumio go-librespot[24252]: time="2026-04-11T17:24:34+07:00" level=info msg="running go-librespot 0.7.1" Apr 11 17:24:34 volumio go-librespot[24252]: time="2026-04-11T17:24:34+07:00" level=debug msg="app state loaded" Apr 11 17:24:34 volumio go-librespot[24252]: time="2026-04-11T17:24:34+07:00" level=debug msg="stored credentials not found" Apr 11 17:24:34 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Apr 11 17:24:34 volumio volumio[24036]: info: Starting Shairport Sync Apr 11 17:24:34 volumio go-librespot[24252]: time="2026-04-11T17:24:34+07:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 11 17:24:34 volumio volumio[24036]: info: Starting Shairport Sync Apr 11 17:24:34 volumio volumio[24036]: info: Starting Shairport Sync Apr 11 17:24:34 volumio sudo[24261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:24:34 volumio sudo[24261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:34 volumio sudo[24263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:24:34 volumio sudo[24263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:34 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Apr 11 17:24:34 volumio sudo[24266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:24:34 volumio sudo[24266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:34 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 17:24:34 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Apr 11 17:24:34 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 17:24:34 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:24:34 volumio systemd[1]: shairport-sync.service: Consumed 8.065s CPU time. Apr 11 17:24:34 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Apr 11 17:24:34 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Apr 11 17:24:34 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Apr 11 17:24:34 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Apr 11 17:24:34 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Apr 11 17:24:34 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:24:34 volumio sudo[24263]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:34 volumio sudo[24261]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:34 volumio volumio[24036]: info: Shairport-Sync Started Apr 11 17:24:34 volumio volumio[24036]: Error adding Membership: Error: addMembership EINVAL Apr 11 17:24:34 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 17:24:34 volumio volumio[24036]: info: Shairport-Sync Started Apr 11 17:24:34 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 17:24:34 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:24:34 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:24:34 volumio sudo[24266]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:34 volumio volumio[24036]: info: Shairport-Sync Started Apr 11 17:24:34 volumio mpd[24222]: 2026-04-11T17:24:34 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 17:24:34 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 11 17:24:34 volumio sudo[24173]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:34 volumio sudo[24189]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:34 volumio volumio[24036]: info: Completed starting Core Plugins Apr 11 17:24:34 volumio volumio[24036]: info: ------------------------------------------- Apr 11 17:24:34 volumio volumio[24036]: info: ----- MyVolumio plugins startup ---- Apr 11 17:24:34 volumio volumio[24036]: info: ------------------------------------------- Apr 11 17:24:34 volumio volumio[24036]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 11 17:24:34 volumio volumio[24036]: error: MPD error: The expression evaluated to a falsy value: Apr 11 17:24:34 volumio volumio[24036]: assert.ok(self.idling) Apr 11 17:24:34 volumio volumio[24036]: error: The expression evaluated to a falsy value: Apr 11 17:24:34 volumio volumio[24036]: assert.ok(self.idling) Apr 11 17:24:34 volumio volumio[24036]: error: updateQueue error: null Apr 11 17:24:34 volumio volumio[24036]: info: MPD running with PID24222 Apr 11 17:24:34 volumio volumio[24036]: ,establishing connection Apr 11 17:24:34 volumio volumio[24036]: error: updateQueue error: null Apr 11 17:24:35 volumio volumio[24036]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 11 17:24:35 volumio volumio[24036]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:35 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:35 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 17:24:35 volumio volumio[24036]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 17:24:35 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:35 volumio volumio[24036]: info: Listing playlists Apr 11 17:24:36 volumio volumio-remote-updater[994]: [2026-04-11 17:24:36] [connect] Successful connection Apr 11 17:24:36 volumio volumio-remote-updater[994]: [2026-04-11 17:24:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1775903076 101 Apr 11 17:24:36 volumio volumio[24036]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 6 Apr 11 17:24:37 volumio volumio[24036]: info: go-librespot daemon successfully initialized Apr 11 17:24:37 volumio volumio[24036]: verbose: New Socket.io Connection to 192.168.1.24 from 192.168.1.22 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 11 17:24:37 volumio go-librespot[24252]: time="2026-04-11T17:24:37+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 11 17:24:37 volumio go-librespot[24252]: time="2026-04-11T17:24:37+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 11 17:24:37 volumio go-librespot[24252]: time="2026-04-11T17:24:37+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 11 17:24:37 volumio go-librespot[24252]: time="2026-04-11T17:24:37+07:00" level=info msg="zeroconf server listening on port 37533" Apr 11 17:24:37 volumio go-librespot[24252]: time="2026-04-11T17:24:37+07:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 11 17:24:38 volumio volumio[24036]: info: Discovery: adding 16ab4786-380b-439f-ac19-d13072ee369e Apr 11 17:24:38 volumio volumio[24036]: info: Discovery: Found device Volumio Apr 11 17:24:38 volumio volumio[24036]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:38 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:38 volumio volumio[24036]: info: Discovery: this is already registered, 16ab4786-380b-439f-ac19-d13072ee369e Apr 11 17:24:38 volumio volumio[24036]: info: Discovery: Found device Volumio Apr 11 17:24:38 volumio volumio[24036]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:38 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:38 volumio volumio[24036]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:38 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:38 volumio go-librespot[24252]: time="2026-04-11T17:24:38+07:00" level=debug msg="obtained new client token: AABTAb83ssWd4HK1b2hqXh5YUQoivpBK+msPvo8AKILtiWFAn/OqpBuF55G8IvreEye0QRRWEjZEYgCfE/SNyyPaDkFlfgILIqbm3l/PM3FmpDML8u8kt19UsYTPUgUpO/LfW5zjSoyXslENiLKVKLQr6evAZrGftZM/GoxwD6DdgwfwrAAh/oGMNk2Bvd1SfifhpEGsTpmZwlBGT9Cokgao9RUO2bZZu4I0u7LX+o4cW82fOk2o6tNGrA==" Apr 11 17:24:38 volumio go-librespot[24252]: time="2026-04-11T17:24:38+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Apr 11 17:24:38 volumio go-librespot[24252]: time="2026-04-11T17:24:38+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.241.202:443: connect: connection refused" Apr 11 17:24:38 volumio go-librespot[24252]: time="2026-04-11T17:24:38+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Apr 11 17:24:38 volumio go-librespot[24252]: time="2026-04-11T17:24:38+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:38 volumio go-librespot[24252]: time="2026-04-11T17:24:38+07:00" level=debug msg="completed challenge" Apr 11 17:24:38 volumio go-librespot[24252]: time="2026-04-11T17:24:38+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:39 volumio volumio[24036]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 11 17:24:39 volumio go-librespot[24252]: time="2026-04-11T17:24:39+07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Apr 11 17:24:39 volumio go-librespot[24252]: time="2026-04-11T17:24:39+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:39 volumio go-librespot[24252]: time="2026-04-11T17:24:39+07:00" level=debug msg="completed challenge" Apr 11 17:24:39 volumio volumio[24036]: info: Volumio called home Apr 11 17:24:39 volumio volumio[24036]: info: New Spotify access tokenBQCqWNhBlO... Apr 11 17:24:39 volumio volumio[24036]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 11 17:24:40 volumio volumio[24036]: SPOTIFY: User informations: {"country":"IN","display_name":"Tân Tit","email":"tannh81.bvsc@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/315qee2w2i26yi4mzflhupgvlcc4"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/315qee2w2i26yi4mzflhupgvlcc4","id":"315qee2w2i26yi4mzflhupgvlcc4","images":[],"product":"free","type":"user","uri":"spotify:user:315qee2w2i26yi4mzflhupgvlcc4"} Apr 11 17:24:40 volumio volumio[24036]: info: Spotify Successfully logged in Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 17:24:40 volumio volumio[24036]: info: [1775903080034] CoreMusicLibrary::Adding element Spotify Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:40 volumio volumio[24036]: Cannot find translation for source YouTube2 Apr 11 17:24:40 volumio volumio[24036]: Cannot find translation for source YouTube Music Apr 11 17:24:40 volumio volumio[24036]: Cannot find translation for source Spotify Apr 11 17:24:40 volumio volumio[24036]: verbose: New Socket.io Connection to 192.168.1.24 from 192.168.1.22 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 11 17:24:40 volumio volumio[24036]: info: Initializing connection to go-librespot Websocket Apr 11 17:24:40 volumio go-librespot[24252]: time="2026-04-11T17:24:40+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:40 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:40 volumio go-librespot[24252]: time="2026-04-11T17:24:40+07:00" level=debug msg="new websocket client" Apr 11 17:24:40 volumio volumio[24036]: info: Connection to go-librespot Websocket established Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:40 volumio volumio[24036]: info: Listing playlists Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::volumioGetQueue Apr 11 17:24:40 volumio volumio[24036]: info: CoreStateMachine::getQueue Apr 11 17:24:40 volumio volumio[24036]: info: CorePlayQueue::getQueue Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:40 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:40 volumio volumio[24036]: info: Listing playlists Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::volumioGetQueue Apr 11 17:24:40 volumio volumio[24036]: info: CoreStateMachine::getQueue Apr 11 17:24:40 volumio volumio[24036]: info: CorePlayQueue::getQueue Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:40 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:40 volumio volumio[24036]: info: Listing playlists Apr 11 17:24:40 volumio volumio[24036]: info: CoreCommandRouter::volumioGetQueue Apr 11 17:24:40 volumio volumio[24036]: info: CoreStateMachine::getQueue Apr 11 17:24:40 volumio volumio[24036]: info: CorePlayQueue::getQueue Apr 11 17:24:41 volumio go-librespot[24252]: time="2026-04-11T17:24:41+07:00" level=debug msg="connected to ap-gew1.spotify.com:443" Apr 11 17:24:41 volumio go-librespot[24252]: time="2026-04-11T17:24:41+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:41 volumio go-librespot[24252]: time="2026-04-11T17:24:41+07:00" level=debug msg="completed challenge" Apr 11 17:24:41 volumio go-librespot[24252]: time="2026-04-11T17:24:41+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:42 volumio sudo[24319]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 17:24:42 volumio sudo[24319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:42 volumio sudo[24319]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:42 volumio sudo[24321]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 17:24:42 volumio sudo[24321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:42 volumio sudo[24321]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:42 volumio sudo[24325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 11 17:24:42 volumio sudo[24325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:42 volumio sudo[24325]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:42 volumio volumio[24036]: info: Upmpdcli Daemon Started Apr 11 17:24:42 volumio go-librespot[24252]: time="2026-04-11T17:24:42+07:00" level=debug msg="connected to ap-gew4.spotify.com:80" Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 11 17:24:42 volumio volumio[24036]: info: Adding plugin bluetooth to MyMusic Plugins Apr 11 17:24:42 volumio volumio[24036]: info: Adding plugin multiroom to MyMusic Plugins Apr 11 17:24:42 volumio volumio[24036]: info: Adding plugin metavolumio to MyMusic Plugins Apr 11 17:24:42 volumio volumio[24036]: info: Adding plugin cd_controller to MyMusic Plugins Apr 11 17:24:42 volumio volumio[24036]: info: Adding plugin qobuzconnect to MyMusic Plugins Apr 11 17:24:42 volumio volumio[24036]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 11 17:24:42 volumio volumio[24036]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 11 17:24:42 volumio volumio[24036]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 11 17:24:43 volumio volumio[24036]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 11 17:24:43 volumio volumio[24036]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 11 17:24:43 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:43 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:43 volumio volumio[24036]: info: Starting MyVolumio Remote Streaming Endpoints Apr 11 17:24:43 volumio volumio[24036]: info: MyVolumio login type: Token Apr 11 17:24:43 volumio volumio[24036]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 11 17:24:43 volumio volumio[24036]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 11 17:24:43 volumio go-librespot[24252]: time="2026-04-11T17:24:43+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:43 volumio go-librespot[24252]: time="2026-04-11T17:24:43+07:00" level=debug msg="completed challenge" Apr 11 17:24:43 volumio volumio[24036]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 11 17:24:43 volumio volumio[24036]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 11 17:24:43 volumio volumio[24036]: info: Streaming services startup Apr 11 17:24:43 volumio volumio[24036]: info: Starting Streaming Daemon Apr 11 17:24:43 volumio sudo[24331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 11 17:24:43 volumio sudo[24331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:43 volumio volumio[24036]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 11 17:24:43 volumio sudo[24331]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:43 volumio volumio[24036]: info: Getting Spotify volume Apr 11 17:24:43 volumio volumio[24036]: error: Cannot start Volumio Streaming Daemon Apr 11 17:24:43 volumio volumio[24036]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 11 17:24:43 volumio volumio[24036]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 11 17:24:43 volumio volumio[24036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 11 17:24:43 volumio volumio[24036]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:43 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:43 volumio go-librespot[24252]: time="2026-04-11T17:24:43+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:43 volumio sudo[24085]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:44 volumio go-librespot[24252]: time="2026-04-11T17:24:44+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Apr 11 17:24:44 volumio go-librespot[24252]: time="2026-04-11T17:24:44+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:44 volumio go-librespot[24252]: time="2026-04-11T17:24:44+07:00" level=debug msg="completed challenge" Apr 11 17:24:44 volumio go-librespot[24252]: time="2026-04-11T17:24:44+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:44 volumio volumio[24036]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 11 17:24:44 volumio volumio[24036]: info: Received Get System Info Apr 11 17:24:44 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 17:24:44 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 17:24:44 volumio volumio[24036]: info: Discovery: Getting this device information Apr 11 17:24:44 volumio volumio[24036]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:44 volumio volumio[24036]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:44 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 17:24:44 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 17:24:44 volumio volumio[24036]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 17:24:44 volumio go-librespot[24252]: time="2026-04-11T17:24:44+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Apr 11 17:24:44 volumio go-librespot[24252]: time="2026-04-11T17:24:44+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:44 volumio go-librespot[24252]: time="2026-04-11T17:24:44+07:00" level=debug msg="completed challenge" Apr 11 17:24:44 volumio volumio[24036]: info: MyVolumio token set successfully Apr 11 17:24:44 volumio volumio[24036]: info: MYVOLUMIO: Adding device Apr 11 17:24:44 volumio volumio[24036]: info: MYVOLUMIO: Evaluating Server Apr 11 17:24:44 volumio go-librespot[24252]: time="2026-04-11T17:24:44+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:44 volumio go-librespot[24252]: time="2026-04-11T17:24:44+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 11 17:24:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 11 17:24:44 volumio volumio[24036]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 17:24:44 volumio volumio[24036]: Error: socket hang up Apr 11 17:24:44 volumio volumio[24036]: at connResetException (node:internal/errors:720:14) Apr 11 17:24:44 volumio volumio[24036]: at Socket.socketOnEnd (node:_http_client:519:23) Apr 11 17:24:44 volumio volumio[24036]: at Socket.emit (node:events:526:35) Apr 11 17:24:44 volumio volumio[24036]: at endReadableNT (node:internal/streams/readable:1376:12) Apr 11 17:24:44 volumio volumio[24036]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Apr 11 17:24:44 volumio volumio[24036]: code: 'ECONNRESET', Apr 11 17:24:44 volumio volumio[24036]: response: undefined Apr 11 17:24:44 volumio volumio[24036]: } Apr 11 17:24:44 volumio volumio[24036]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 17:24:45 volumio sudo[24355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 17:23' Apr 11 17:24:45 volumio sudo[24355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:45 volumio sudo[24355]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:45 volumio volumio-remote-updater[994]: [2026-04-11 17:24:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 11 17:24:45 volumio volumio5-onboarding[13554]: time=2026-04-11T17:24:45.073+07:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Apr 11 17:24:45 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 11 17:24:45 volumio volumio-remote-updater[994]: [2026-04-11 17:24:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 11 17:24:45 volumio volumio5-onboarding[13554]: time=2026-04-11T17:24:45.075+07:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Apr 11 17:24:45 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 11 17:24:45 volumio systemd[1]: volumio.service: Consumed 7.220s CPU time. Apr 11 17:24:45 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 11 17:24:45 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 11 17:24:45 volumio volumio5-onboarding[13554]: time=2026-04-11T17:24:45.288+07:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 11 17:24:45 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 33. Apr 11 17:24:45 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 11 17:24:45 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 11 17:24:45 volumio systemd[1]: volumio.service: Consumed 7.220s CPU time. Apr 11 17:24:45 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 11 17:24:45 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Apr 11 17:24:45 volumio volumio[24367]: info: ------------------------------------------- Apr 11 17:24:45 volumio volumio[24367]: info: ----- Volumio3 ---- Apr 11 17:24:45 volumio volumio[24367]: info: ------------------------------------------- Apr 11 17:24:45 volumio volumio[24367]: info: ----- System startup ---- Apr 11 17:24:45 volumio volumio[24367]: info: ------------------------------------------- Apr 11 17:24:45 volumio volumio[24367]: info: MYVOLUMIO Environment detected Apr 11 17:24:45 volumio volumio[24367]: info: Plugin folders cleanup Apr 11 17:24:45 volumio volumio[24367]: info: Scanning into folder /volumio/app/plugins/ Apr 11 17:24:45 volumio volumio[24367]: info: Scanning category audio_interface Apr 11 17:24:45 volumio volumio[24367]: info: Scanning category miscellanea Apr 11 17:24:45 volumio volumio[24367]: info: Scanning category music_service Apr 11 17:24:45 volumio volumio[24367]: info: Scanning category plugins.json Apr 11 17:24:45 volumio volumio[24367]: info: Scanning category system_controller Apr 11 17:24:45 volumio volumio[24367]: info: Scanning category user_interface Apr 11 17:24:45 volumio volumio[24367]: info: Scanning into folder /data/plugins/ Apr 11 17:24:45 volumio volumio[24367]: info: Scanning category music_service Apr 11 17:24:45 volumio volumio[24367]: info: Plugin folders cleanup completed Apr 11 17:24:45 volumio volumio[24367]: info: ------------------------------------------- Apr 11 17:24:45 volumio volumio[24367]: info: ----- Core plugins startup ---- Apr 11 17:24:45 volumio volumio[24367]: info: ------------------------------------------- Apr 11 17:24:45 volumio volumio[24367]: info: Loading plugins from folder /volumio/app/plugins/ Apr 11 17:24:45 volumio volumio[24367]: info: Adding plugin upnp to MyMusic Plugins Apr 11 17:24:45 volumio volumio[24367]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 11 17:24:45 volumio volumio[24367]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 11 17:24:45 volumio volumio[24367]: info: Loading plugins from folder /data/plugins/ Apr 11 17:24:45 volumio volumio[24367]: info: Loading plugin "system"... Apr 11 17:24:45 volumio volumio[24367]: info: Loading plugin "appearance"... Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "network"... Apr 11 17:24:46 volumio volumio[24367]: info: Refreshing Cached IP Addresses Apr 11 17:24:46 volumio sudo[24396]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 17:24:46 volumio sudo[24396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:46 volumio sudo[24398]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 17:24:46 volumio sudo[24398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:46 volumio sudo[24396]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:46 volumio sudo[24398]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "services"... Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "volumio5onboarding"... Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "alsa_controller"... Apr 11 17:24:46 volumio sudo[24407]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 11 17:24:46 volumio sudo[24407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:46 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "wizard"... Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "networkfs"... Apr 11 17:24:46 volumio volumio[24367]: info: Starting Udev Watcher for removable devices Apr 11 17:24:46 volumio volumio[24367]: info: Ignoring mount for partition: boot Apr 11 17:24:46 volumio volumio[24367]: info: Ignoring mount for partition: volumio Apr 11 17:24:46 volumio volumio[24367]: info: Ignoring mount for partition: volumio_data Apr 11 17:24:46 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "volumio_command_line_client"... Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "upnp"... Apr 11 17:24:46 volumio volumio[24367]: info: [1775903086197] Starting Upmpd Daemon Apr 11 17:24:46 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "my_music"... Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "mpd"... Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "upnp_browser"... Apr 11 17:24:46 volumio volumio[24367]: info: Starting UPNP Browser Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "alarm-clock"... Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "airplay_emulation"... Apr 11 17:24:46 volumio volumio[24367]: info: Starting Shairport Sync Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "last_100"... Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "webradio"... Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "i2s_dacs"... Apr 11 17:24:46 volumio volumio[24367]: info: I2S DAC not set, start Auto-detection Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "volumiodiscovery"... Apr 11 17:24:46 volumio volumio[24367]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 17:24:46 volumio volumio[24367]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 17:24:46 volumio volumio[24367]: *** WARNING *** For more information see Apr 11 17:24:46 volumio volumio[24367]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 17:24:46 volumio volumio[24367]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 17:24:46 volumio volumio[24367]: *** WARNING *** For more information see Apr 11 17:24:46 volumio node[24367]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 17:24:46 volumio node[24367]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 17:24:46 volumio node[24367]: *** WARNING *** For more information see Apr 11 17:24:46 volumio node[24367]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 17:24:46 volumio node[24367]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 17:24:46 volumio node[24367]: *** WARNING *** For more information see Apr 11 17:24:46 volumio volumio[24367]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 11 17:24:46 volumio volumio[24367]: info: Discovery: Started advertising with name: Volumio Apr 11 17:24:46 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "spop"... Apr 11 17:24:46 volumio volumio[24367]: info: Loading plugin "youtube2"... Apr 11 17:24:47 volumio volumio[24367]: info: Loading plugin "ytmusic"... Apr 11 17:24:47 volumio volumio[24367]: info: Loading plugin "outputs"... Apr 11 17:24:47 volumio volumio[24367]: info: Loading plugin "albumart"... Apr 11 17:24:47 volumio volumio[24367]: info: Plugin example_plugin is not enabled Apr 11 17:24:47 volumio volumio[24367]: info: Loading plugin "inputs"... Apr 11 17:24:47 volumio volumio[24367]: info: Loading plugin "updater_comm"... Apr 11 17:24:47 volumio volumio[24367]: info: Plugin mpdemulation is not enabled Apr 11 17:24:47 volumio volumio[24367]: info: Loading plugin "rest_api"... Apr 11 17:24:47 volumio volumio[24367]: info: Loading plugin "websocket"... Apr 11 17:24:47 volumio volumio[24367]: info: Starting Socket.io Server version 1.7.4 Apr 11 17:24:47 volumio volumio[24367]: info: Loading plugin "RoonBridge"... Apr 11 17:24:47 volumio volumio[24432]: Forking 3 albumart workers Apr 11 17:24:47 volumio volumio[24367]: info: Applying required configuration parameters for plugin RoonBridge Apr 11 17:24:47 volumio volumio[24367]: info: Loading i18n strings for locale vi Apr 11 17:24:47 volumio volumio[24367]: Updating browse sources language Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::initPlayerControls Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: Express server listening on port 3000 Apr 11 17:24:47 volumio volumio[24367]: [Metrics] WebUI: 1s 821.79ms Apr 11 17:24:47 volumio volumio[24367]: info: Setting Device type: x86 Apr 11 17:24:47 volumio volumio[24367]: info: CoreStateMachine::resetVolumioState Apr 11 17:24:47 volumio volumio[24367]: info: CoreStateMachine::getcurrentVolume Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 11 17:24:47 volumio volumio[24367]: info: Completed loading Core Plugins Apr 11 17:24:47 volumio volumio[24367]: info: Preparing to generate the ALSA configuration file Apr 11 17:24:47 volumio volumio[24367]: info: Volumio Network Manager: Network status updated: 2 Apr 11 17:24:47 volumio volumio[24367]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 11 17:24:47 volumio volumio[24444]: Starting albumart workers Apr 11 17:24:47 volumio volumio[24367]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 11 17:24:47 volumio volumio[24442]: Starting albumart workers Apr 11 17:24:47 volumio volumio[24367]: Unable to parse: Apr 11 17:24:47 volumio volumio[24367]: Simple mixer control 'IEC958',0 Apr 11 17:24:47 volumio volumio[24367]: Capabilities: pswitch pswitch-joined Apr 11 17:24:47 volumio volumio[24367]: Playback channels: Mono Apr 11 17:24:47 volumio volumio[24367]: Mono: Playback [on] Apr 11 17:24:47 volumio volumio[24367]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 11 17:24:47 volumio volumio[24443]: Starting albumart workers Apr 11 17:24:47 volumio volumio[24367]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Apr 11 17:24:47 volumio volumio[24367]: info: Reloading queue from file Apr 11 17:24:47 volumio volumio[24367]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 11 17:24:47 volumio volumio[24367]: Unable to parse: Apr 11 17:24:47 volumio volumio[24367]: Simple mixer control 'IEC958',0 Apr 11 17:24:47 volumio volumio[24367]: Capabilities: pswitch pswitch-joined Apr 11 17:24:47 volumio volumio[24367]: Playback channels: Mono Apr 11 17:24:47 volumio volumio[24367]: Mono: Playback [on] Apr 11 17:24:47 volumio volumio[24367]: info: VolumeController:: Volume=undefined Mute =false Apr 11 17:24:47 volumio volumio[24367]: info: CoreStateMachine::pushState Apr 11 17:24:47 volumio volumio[24367]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::volumioPushState Apr 11 17:24:47 volumio volumio[24367]: info: CoreStateMachine::updateTrackBlock Apr 11 17:24:47 volumio volumio[24367]: info: CorePlayQueue::getTrackBlock Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:24:47 volumio volumio[24367]: info: Asound.conf file unchanged, so no further update is needed Apr 11 17:24:47 volumio volumio[24367]: info: Output device has changed, restarting MPD Apr 11 17:24:47 volumio sudo[24487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 17:24:47 volumio sudo[24487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:47 volumio sudo[24487]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:47 volumio volumio[24367]: info: Output device has changed, restarting Shairport Sync Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:47 volumio sudo[24490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 17:24:47 volumio sudo[24490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:47 volumio volumio[24367]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:24:47 volumio volumio[24367]: info: ___________ START PLUGINS ___________ Apr 11 17:24:47 volumio volumio[24367]: info: ControllerMpd::onStart: Initializing MPD Apr 11 17:24:47 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 11 17:24:47 volumio volumio[24367]: info: Creating MPD Configuration file Apr 11 17:24:47 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 11 17:24:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 17:24:47 volumio sudo[24500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 11 17:24:47 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 11 17:24:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 17:24:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 17:24:47 volumio sudo[24500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 17:24:47 volumio volumio[24367]: info: [1775903087802] CoreMusicLibrary::Adding element Máy chủ Media Apr 11 17:24:47 volumio sudo[24507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:47 volumio sudo[24507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:47 volumio sudo[24512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 17:24:47 volumio sudo[24512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:47 volumio sudo[24507]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:47 volumio volumio[24367]: info: UPNP Browser: Client initialized successfully Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 17:24:47 volumio sudo[24500]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:47 volumio volumio[24367]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:47 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 11 17:24:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 17:24:47 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 11 17:24:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 17:24:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 17:24:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 17:24:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 17:24:47 volumio volumio[24367]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 17:24:47 volumio volumio[24367]: info: [1775903087865] CoreMusicLibrary::Adding element Last_100 Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 17:24:47 volumio volumio[24367]: info: [1775903087866] CoreMusicLibrary::Adding element Webradio Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 17:24:47 volumio volumio[24367]: info: Initializing BBC Radios Apr 11 17:24:47 volumio sudo[24527]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 11 17:24:47 volumio sudo[24527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 17:24:47 volumio sudo[24527]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:47 volumio volumio[24367]: info: Creating Spotify config file Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 17:24:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 11 17:24:47 volumio go-librespot[24545]: go-librespot daemon starting... Apr 11 17:24:47 volumio go-librespot[24546]: time="2026-04-11T17:24:47+07:00" level=info msg="running go-librespot 0.7.1" Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 17:24:47 volumio volumio[24367]: info: [1775903087975] CoreMusicLibrary::Adding element YouTube2 Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:47 volumio volumio[24367]: Cannot find translation for source YouTube2 Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 17:24:47 volumio volumio[24367]: info: [1775903087982] CoreMusicLibrary::Adding element YouTube Music Apr 11 17:24:47 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:47 volumio volumio[24367]: Cannot find translation for source YouTube2 Apr 11 17:24:47 volumio volumio[24367]: Cannot find translation for source YouTube Music Apr 11 17:24:47 volumio volumio[24367]: info: Volumio Calling Home Apr 11 17:24:48 volumio sudo[24553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Apr 11 17:24:48 volumio sudo[24553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:48 volumio sudo[24553]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:48 volumio volumio[24367]: info: CoreStateMachine::setRepeat null single undefined Apr 11 17:24:48 volumio volumio[24367]: info: CoreStateMachine::pushState Apr 11 17:24:48 volumio volumio[24367]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::volumioPushState Apr 11 17:24:48 volumio volumio[24367]: info: CoreStateMachine::setRandom null Apr 11 17:24:48 volumio volumio[24367]: info: CoreStateMachine::pushState Apr 11 17:24:48 volumio volumio[24367]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::volumioPushState Apr 11 17:24:48 volumio volumio[24367]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Apr 11 17:24:48 volumio volumio[24367]: info: MPD Permissions set Apr 11 17:24:48 volumio volumio[24367]: info: MPD Permissions set Apr 11 17:24:48 volumio volumio[24367]: Unable to parse: Apr 11 17:24:48 volumio volumio[24367]: Simple mixer control 'IEC958',0 Apr 11 17:24:48 volumio volumio[24367]: Capabilities: pswitch pswitch-joined Apr 11 17:24:48 volumio volumio[24367]: Playback channels: Mono Apr 11 17:24:48 volumio volumio[24367]: Mono: Playback [on] Apr 11 17:24:48 volumio volumio[24367]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 11 17:24:48 volumio volumio[24367]: error: Upnp client error: Error: This socket has been ended by the other party Apr 11 17:24:48 volumio volumio[24367]: info: Spotify config file written Apr 11 17:24:48 volumio volumio[24367]: verbose: New Socket.io Connection to 192.168.1.24 from 192.168.1.22 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 11 17:24:48 volumio sudo[24562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 11 17:24:48 volumio sudo[24562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 11 17:24:48 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 11 17:24:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:24:48 volumio volumio[24367]: info: No need to fix Spotify hosts Apr 11 17:24:48 volumio volumio[24367]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 11 17:24:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 11 17:24:48 volumio go-librespot[24579]: go-librespot daemon starting... Apr 11 17:24:48 volumio volumio[24367]: Unable to parse: Apr 11 17:24:48 volumio volumio[24367]: Simple mixer control 'IEC958',0 Apr 11 17:24:48 volumio volumio[24367]: Capabilities: pswitch pswitch-joined Apr 11 17:24:48 volumio volumio[24367]: Playback channels: Mono Apr 11 17:24:48 volumio volumio[24367]: Mono: Playback [on] Apr 11 17:24:48 volumio volumio[24367]: info: VolumeController:: Volume=undefined Mute =false Apr 11 17:24:48 volumio volumio[24367]: info: CoreStateMachine::pushState Apr 11 17:24:48 volumio volumio[24367]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:48 volumio sudo[24562]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::volumioPushState Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:48 volumio volumio[24367]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:48 volumio go-librespot[24582]: time="2026-04-11T17:24:48+07:00" level=info msg="running go-librespot 0.7.1" Apr 11 17:24:48 volumio go-librespot[24582]: time="2026-04-11T17:24:48+07:00" level=debug msg="app state loaded" Apr 11 17:24:48 volumio go-librespot[24582]: time="2026-04-11T17:24:48+07:00" level=debug msg="stored credentials not found" Apr 11 17:24:48 volumio volumio[24367]: info: Listing playlists Apr 11 17:24:48 volumio go-librespot[24582]: time="2026-04-11T17:24:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 11 17:24:48 volumio volumio[24367]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Apr 11 17:24:48 volumio volumio[24367]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Apr 11 17:24:48 volumio volumio[24367]: info: Starting Shairport Sync Apr 11 17:24:48 volumio volumio[24367]: info: Starting Shairport Sync Apr 11 17:24:48 volumio volumio[24367]: info: Starting Shairport Sync Apr 11 17:24:48 volumio sudo[24592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:24:48 volumio sudo[24593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:24:48 volumio sudo[24593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:48 volumio sudo[24592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:48 volumio volumio[24367]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Apr 11 17:24:48 volumio volumio[24367]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Apr 11 17:24:48 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 17:24:48 volumio volumio[24367]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Apr 11 17:24:48 volumio volumio[24367]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Apr 11 17:24:48 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 17:24:48 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:24:48 volumio systemd[1]: shairport-sync.service: Consumed 1.631s CPU time. Apr 11 17:24:48 volumio sudo[24597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:24:48 volumio sudo[24597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:48 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:24:48 volumio sudo[24593]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:48 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 17:24:48 volumio volumio[24367]: info: Shairport-Sync Started Apr 11 17:24:48 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 17:24:48 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:24:48 volumio volumio[24367]: Error adding Membership: Error: addMembership EINVAL Apr 11 17:24:48 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:24:48 volumio sudo[24592]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:48 volumio sudo[24597]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:48 volumio volumio[24367]: info: Shairport-Sync Started Apr 11 17:24:48 volumio volumio[24367]: info: Shairport-Sync Started Apr 11 17:24:48 volumio mpd[24544]: 2026-04-11T17:24:48 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 17:24:48 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 11 17:24:48 volumio sudo[24512]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:48 volumio sudo[24490]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:48 volumio volumio[24367]: info: Completed starting Core Plugins Apr 11 17:24:48 volumio volumio[24367]: info: ------------------------------------------- Apr 11 17:24:48 volumio volumio[24367]: info: ----- MyVolumio plugins startup ---- Apr 11 17:24:48 volumio volumio[24367]: info: ------------------------------------------- Apr 11 17:24:48 volumio volumio[24367]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 11 17:24:48 volumio volumio[24367]: error: MPD error: The expression evaluated to a falsy value: Apr 11 17:24:48 volumio volumio[24367]: assert.ok(self.idling) Apr 11 17:24:48 volumio volumio[24367]: error: The expression evaluated to a falsy value: Apr 11 17:24:48 volumio volumio[24367]: assert.ok(self.idling) Apr 11 17:24:48 volumio volumio[24367]: info: MPD running with PID24544 Apr 11 17:24:48 volumio volumio[24367]: ,establishing connection Apr 11 17:24:48 volumio volumio[24367]: error: updateQueue error: null Apr 11 17:24:48 volumio volumio[24367]: error: updateQueue error: null Apr 11 17:24:48 volumio volumio[24367]: info: Discovery: adding 16ab4786-380b-439f-ac19-d13072ee369e Apr 11 17:24:48 volumio volumio[24367]: info: Discovery: Found device Volumio Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:48 volumio volumio[24367]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:48 volumio volumio[24367]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:48 volumio volumio[24367]: info: Discovery: this is already registered, 16ab4786-380b-439f-ac19-d13072ee369e Apr 11 17:24:48 volumio volumio[24367]: info: Discovery: Found device Volumio Apr 11 17:24:48 volumio volumio[24367]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:48 volumio volumio[24367]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:48 volumio go-librespot[24582]: time="2026-04-11T17:24:48+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 11 17:24:48 volumio go-librespot[24582]: time="2026-04-11T17:24:48+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 11 17:24:48 volumio go-librespot[24582]: time="2026-04-11T17:24:48+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 11 17:24:48 volumio go-librespot[24582]: time="2026-04-11T17:24:48+07:00" level=info msg="zeroconf server listening on port 46139" Apr 11 17:24:48 volumio go-librespot[24582]: time="2026-04-11T17:24:48+07:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 11 17:24:49 volumio volumio[24367]: info: New Spotify access tokenBQBQ6lqT7E... Apr 11 17:24:49 volumio volumio[24367]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 11 17:24:49 volumio volumio[24367]: info: Volumio called home Apr 11 17:24:49 volumio go-librespot[24582]: time="2026-04-11T17:24:49+07:00" level=debug msg="obtained new client token: AAAfM62bsc88xipKaKsD3uJIwPBvPqwo+HoyYUOGBo3IkpE9ZNlXXLCD119N5oSiS9HFxqxmy6JdXRJZvREgKk4SEOQrvas5BSxNzbNEVz1NV20HsAGBFnlmLZOsYGJdjNyVLdswmdBC97edLMOjj2F8RIHI/bHuzIHi+DTgXFCoPvxStqEMjhb2dPKB8cSLh5nznmvQYjWiiPXoOYtykZPOFju3yvZJOmY7rd4K9mclBN+k4CxbYYQ=" Apr 11 17:24:49 volumio go-librespot[24582]: time="2026-04-11T17:24:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Apr 11 17:24:49 volumio go-librespot[24582]: time="2026-04-11T17:24:49+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:49 volumio go-librespot[24582]: time="2026-04-11T17:24:49+07:00" level=debug msg="completed challenge" Apr 11 17:24:49 volumio volumio[24367]: verbose: New Socket.io Connection to 192.168.1.24 from 192.168.1.22 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 11 17:24:49 volumio volumio[24367]: SPOTIFY: User informations: {"country":"IN","display_name":"Tân Tit","email":"tannh81.bvsc@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/315qee2w2i26yi4mzflhupgvlcc4"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/315qee2w2i26yi4mzflhupgvlcc4","id":"315qee2w2i26yi4mzflhupgvlcc4","images":[],"product":"free","type":"user","uri":"spotify:user:315qee2w2i26yi4mzflhupgvlcc4"} Apr 11 17:24:49 volumio volumio[24367]: info: Spotify Successfully logged in Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 17:24:49 volumio volumio[24367]: info: [1775903089454] CoreMusicLibrary::Adding element Spotify Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:49 volumio volumio[24367]: Cannot find translation for source YouTube2 Apr 11 17:24:49 volumio volumio[24367]: Cannot find translation for source YouTube Music Apr 11 17:24:49 volumio volumio[24367]: Cannot find translation for source Spotify Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:49 volumio volumio[24367]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:49 volumio go-librespot[24582]: time="2026-04-11T17:24:49+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:49 volumio volumio[24367]: info: Listing playlists Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::volumioGetQueue Apr 11 17:24:49 volumio volumio[24367]: info: CoreStateMachine::getQueue Apr 11 17:24:49 volumio volumio[24367]: info: CorePlayQueue::getQueue Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:49 volumio volumio[24367]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:49 volumio volumio[24367]: info: Listing playlists Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::volumioGetQueue Apr 11 17:24:49 volumio volumio[24367]: info: CoreStateMachine::getQueue Apr 11 17:24:49 volumio volumio[24367]: info: CorePlayQueue::getQueue Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:49 volumio volumio[24367]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:24:49 volumio volumio[24367]: info: Listing playlists Apr 11 17:24:49 volumio volumio[24367]: info: CoreCommandRouter::volumioGetQueue Apr 11 17:24:49 volumio volumio[24367]: info: CoreStateMachine::getQueue Apr 11 17:24:49 volumio volumio[24367]: info: CorePlayQueue::getQueue Apr 11 17:24:50 volumio volumio-remote-updater[994]: [2026-04-11 17:24:50] [connect] Successful connection Apr 11 17:24:50 volumio volumio-remote-updater[994]: [2026-04-11 17:24:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1775903090 101 Apr 11 17:24:50 volumio volumio[24367]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7 Apr 11 17:24:50 volumio go-librespot[24582]: time="2026-04-11T17:24:50+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Apr 11 17:24:50 volumio go-librespot[24582]: time="2026-04-11T17:24:50+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:50 volumio go-librespot[24582]: time="2026-04-11T17:24:50+07:00" level=debug msg="completed challenge" Apr 11 17:24:50 volumio go-librespot[24582]: time="2026-04-11T17:24:50+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:50 volumio go-librespot[24582]: time="2026-04-11T17:24:50+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Apr 11 17:24:50 volumio go-librespot[24582]: time="2026-04-11T17:24:50+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:50 volumio go-librespot[24582]: time="2026-04-11T17:24:50+07:00" level=debug msg="completed challenge" Apr 11 17:24:50 volumio go-librespot[24582]: time="2026-04-11T17:24:50+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:51 volumio volumio[24367]: info: go-librespot daemon successfully initialized Apr 11 17:24:51 volumio go-librespot[24582]: time="2026-04-11T17:24:51+07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Apr 11 17:24:52 volumio go-librespot[24582]: time="2026-04-11T17:24:52+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:52 volumio go-librespot[24582]: time="2026-04-11T17:24:52+07:00" level=debug msg="completed challenge" Apr 11 17:24:52 volumio go-librespot[24582]: time="2026-04-11T17:24:52+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:53 volumio go-librespot[24582]: time="2026-04-11T17:24:53+07:00" level=debug msg="connected to ap-gew1.spotify.com:443" Apr 11 17:24:53 volumio volumio[24367]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 11 17:24:53 volumio go-librespot[24582]: time="2026-04-11T17:24:53+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:53 volumio go-librespot[24582]: time="2026-04-11T17:24:53+07:00" level=debug msg="completed challenge" Apr 11 17:24:53 volumio go-librespot[24582]: time="2026-04-11T17:24:53+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:54 volumio volumio[24367]: info: Initializing connection to go-librespot Websocket Apr 11 17:24:54 volumio go-librespot[24582]: time="2026-04-11T17:24:54+07:00" level=debug msg="new websocket client" Apr 11 17:24:54 volumio volumio[24367]: info: Connection to go-librespot Websocket established Apr 11 17:24:54 volumio go-librespot[24582]: time="2026-04-11T17:24:54+07:00" level=debug msg="connected to ap-gew4.spotify.com:80" Apr 11 17:24:55 volumio go-librespot[24582]: time="2026-04-11T17:24:55+07:00" level=debug msg="completed keyexchange" Apr 11 17:24:55 volumio go-librespot[24582]: time="2026-04-11T17:24:55+07:00" level=debug msg="completed challenge" Apr 11 17:24:55 volumio go-librespot[24582]: time="2026-04-11T17:24:55+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:55 volumio go-librespot[24582]: time="2026-04-11T17:24:55+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Apr 11 17:24:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 11 17:24:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 11 17:24:55 volumio volumio[24367]: info: Connection to go-librespot Websocket closed Apr 11 17:24:56 volumio sudo[24407]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:56 volumio sudo[24656]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 17:24:56 volumio sudo[24656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:56 volumio sudo[24656]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:56 volumio sudo[24658]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 17:24:56 volumio sudo[24658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:56 volumio sudo[24658]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:56 volumio sudo[24661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 11 17:24:56 volumio sudo[24661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:56 volumio sudo[24661]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:56 volumio volumio[24367]: info: Upmpdcli Daemon Started Apr 11 17:24:56 volumio volumio[24367]: info: Received Get System Info Apr 11 17:24:56 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 17:24:56 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 17:24:56 volumio volumio[24367]: info: Discovery: Getting this device information Apr 11 17:24:56 volumio volumio[24367]: info: CoreCommandRouter::volumioGetState Apr 11 17:24:56 volumio volumio[24367]: info: CorePlayQueue::getTrack 0 Apr 11 17:24:56 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 17:24:56 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 17:24:56 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 11 17:24:56 volumio volumio[24367]: info: Adding plugin bluetooth to MyMusic Plugins Apr 11 17:24:56 volumio volumio[24367]: info: Adding plugin multiroom to MyMusic Plugins Apr 11 17:24:56 volumio volumio[24367]: info: Adding plugin metavolumio to MyMusic Plugins Apr 11 17:24:56 volumio volumio[24367]: info: Adding plugin cd_controller to MyMusic Plugins Apr 11 17:24:56 volumio volumio[24367]: info: Adding plugin qobuzconnect to MyMusic Plugins Apr 11 17:24:56 volumio volumio[24367]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 11 17:24:56 volumio volumio[24367]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 11 17:24:56 volumio volumio[24367]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 11 17:24:57 volumio volumio[24367]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 11 17:24:57 volumio volumio[24367]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 11 17:24:57 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:57 volumio volumio[24367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:24:57 volumio volumio[24367]: info: Starting MyVolumio Remote Streaming Endpoints Apr 11 17:24:57 volumio volumio[24367]: info: MyVolumio login type: Token Apr 11 17:24:57 volumio volumio[24367]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 11 17:24:57 volumio volumio[24367]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 11 17:24:57 volumio volumio[24367]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 11 17:24:57 volumio volumio[24367]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 11 17:24:57 volumio volumio[24367]: info: Streaming services startup Apr 11 17:24:57 volumio volumio[24367]: info: Starting Streaming Daemon Apr 11 17:24:57 volumio sudo[24666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 11 17:24:57 volumio sudo[24666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:24:57 volumio volumio[24367]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 11 17:24:57 volumio sudo[24666]: pam_unix(sudo:session): session closed for user root Apr 11 17:24:57 volumio volumio[24367]: info: Getting Spotify volume Apr 11 17:24:57 volumio volumio[24367]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 17:24:57 volumio volumio[24367]: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 11 17:24:57 volumio volumio[24367]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Apr 11 17:24:57 volumio volumio[24367]: errno: -111, Apr 11 17:24:57 volumio volumio[24367]: code: 'ECONNREFUSED', Apr 11 17:24:57 volumio volumio[24367]: syscall: 'connect', Apr 11 17:24:57 volumio volumio[24367]: address: '127.0.0.1', Apr 11 17:24:57 volumio volumio[24367]: port: 9879, Apr 11 17:24:57 volumio volumio[24367]: response: undefined Apr 11 17:24:57 volumio volumio[24367]: } Apr 11 17:24:57 volumio volumio[24367]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 17:24:57 volumio sudo[24686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 17:23' Apr 11 17:24:57 volumio sudo[24686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"