Jan 25 21:02:00 bureau kernel: usb 1-1-port2: over-current change #207 Jan 25 21:02:00 bureau kernel: usb 1-1-port2: over-current change #208 Jan 25 21:02:00 bureau kernel: usb 1-1-port2: over-current change #209 Jan 25 21:02:00 bureau kernel: usb 1-1-port2: over-current change #210 Jan 25 21:02:00 bureau systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Jan 25 21:02:01 bureau systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 25 21:02:01 bureau systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 21:02:01 bureau systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 21:02:01 bureau upmpdcli[1106]: Could not open config: /tmp/upmpdcli.conf Jan 25 21:02:01 bureau systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 25 21:02:01 bureau systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 25 21:02:01 bureau kernel: usb 1-1-port2: over-current change #211 Jan 25 21:02:01 bureau kernel: usb 1-1-port2: over-current change #212 Jan 25 21:02:01 bureau kernel: usb 1-1-port2: over-current change #213 Jan 25 21:02:01 bureau kernel: usb 1-1-port2: over-current change #214 Jan 25 21:02:02 bureau kernel: usb 1-1-port2: over-current change #215 Jan 25 21:02:02 bureau kernel: usb 1-1-port2: over-current change #216 Jan 25 21:02:02 bureau volumio[1002]: info: Loading plugin "outputs"... Jan 25 21:02:02 bureau volumio[1002]: info: Loading plugin "albumart"... Jan 25 21:02:02 bureau kernel: usb 1-1-port2: over-current change #217 Jan 25 21:02:02 bureau volumio[1002]: info: Plugin example_plugin is not enabled Jan 25 21:02:02 bureau volumio[1002]: info: Loading plugin "inputs"... Jan 25 21:02:02 bureau volumio[1002]: info: Loading plugin "updater_comm"... Jan 25 21:02:02 bureau kernel: usb 1-1-port2: over-current change #218 Jan 25 21:02:03 bureau volumio[1002]: info: Plugin mpdemulation is not enabled Jan 25 21:02:03 bureau volumio[1002]: info: Loading plugin "rest_api"... Jan 25 21:02:03 bureau volumio[1002]: info: Loading plugin "websocket"... Jan 25 21:02:03 bureau volumio[1002]: info: Starting Socket.io Server version 1.7.4 Jan 25 21:02:03 bureau volumio[1002]: info: Loading plugin "podcast"... Jan 25 21:02:03 bureau kernel: usb 1-1-port2: over-current change #219 Jan 25 21:02:03 bureau systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 25 21:02:03 bureau systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 25 21:02:03 bureau systemd[1]: setdatetime-helper.service: Consumed 2.011s CPU time. Jan 25 21:02:03 bureau kernel: usb 1-1-port2: over-current change #220 Jan 25 21:02:03 bureau kernel: usb 1-1-port2: over-current change #221 Jan 25 21:02:04 bureau kernel: usb 1-1-port2: over-current change #222 Jan 25 21:02:04 bureau kernel: usb 1-1-port2: over-current change #223 Jan 25 21:02:04 bureau kernel: usb 1-1-port2: over-current change #224 Jan 25 21:02:04 bureau volumio[1002]: info: ControllerPodcast::constructor Jan 25 21:02:04 bureau kernel: usb 1-1-port2: over-current change #225 Jan 25 21:02:05 bureau kernel: usb 1-1-port2: over-current change #226 Jan 25 21:02:05 bureau volumio[1127]: Forking 3 albumart workers Jan 25 21:02:05 bureau kernel: usb 1-1-port2: over-current change #227 Jan 25 21:02:05 bureau volumio[1002]: info: Loading i18n strings for locale fr Jan 25 21:02:05 bureau volumio[1002]: Updating browse sources language Jan 25 21:02:05 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 21:02:05 bureau kernel: usb 1-1-port2: over-current change #228 Jan 25 21:02:05 bureau kernel: usb 1-1-port2: over-current change #229 Jan 25 21:02:06 bureau kernel: usb 1-1-port2: over-current change #230 Jan 25 21:02:06 bureau kernel: usb 1-1-port2: over-current change #231 Jan 25 21:02:06 bureau kernel: usb 1-1-port2: over-current change #232 Jan 25 21:02:06 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 21:02:06 bureau volumio[1002]: info: CoreCommandRouter::initPlayerControls Jan 25 21:02:06 bureau kernel: usb 1-1-port2: over-current change #233 Jan 25 21:02:06 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:06 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:06 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:06 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:06 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:06 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:06 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:06 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:06 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 21:02:06 bureau volumio[1002]: Express server listening on port 3000 Jan 25 21:02:06 bureau volumio[1002]: [Metrics] WebUI: 21s 911.90ms Jan 25 21:02:07 bureau volumio[1002]: info: CoreStateMachine::resetVolumioState Jan 25 21:02:07 bureau volumio[1002]: info: CoreStateMachine::getcurrentVolume Jan 25 21:02:07 bureau volumio[1002]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 21:02:07 bureau kernel: usb 1-1-port2: over-current change #234 Jan 25 21:02:07 bureau volumio[1002]: info: CoreStateMachine::pushState Jan 25 21:02:07 bureau volumio[1002]: info: CorePlayQueue::getTrack 0 Jan 25 21:02:07 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 21:02:07 bureau volumio[1002]: info: CoreCommandRouter::volumioPushState Jan 25 21:02:07 bureau kernel: usb 1-1-port2: over-current change #235 Jan 25 21:02:07 bureau volumio-remote-updater[616]: [2026-01-25 21:02:07] [connect] Successful connection Jan 25 21:02:07 bureau sudo[1189]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 25 21:02:07 bureau sudo[1189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:07 bureau sudo[1189]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:07 bureau kernel: usb 1-1-port2: over-current change #236 Jan 25 21:02:07 bureau sudo[1191]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 25 21:02:07 bureau sudo[1191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:07 bureau sudo[1191]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:07 bureau kernel: usb 1-1-port2: over-current change #237 Jan 25 21:02:07 bureau volumio[1002]: info: Volumio Network Manager: Network status updated: 1 Jan 25 21:02:08 bureau volumio[1002]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 25 21:02:08 bureau volumio[1002]: wlan0 Interface doesn't support scanning. Jan 25 21:02:08 bureau volumio[1002]: info: Cannot use regular scanning, forcing with ap-force Jan 25 21:02:08 bureau kernel: usb 1-1-port2: over-current change #238 Jan 25 21:02:08 bureau sudo[1199]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 25 21:02:08 bureau sudo[1199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:08 bureau kernel: usb 1-1-port2: over-current change #239 Jan 25 21:02:08 bureau sudo[1199]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:08 bureau volumio[1002]: command failed: No such device (-19) Jan 25 21:02:08 bureau volumio[1002]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 25 21:02:08 bureau volumio[1002]: command failed: No such device (-19) Jan 25 21:02:08 bureau kernel: usb 1-1-port2: over-current change #240 Jan 25 21:02:08 bureau kernel: usb 1-1-port2: over-current change #241 Jan 25 21:02:09 bureau kernel: usb 1-1-port2: over-current change #242 Jan 25 21:02:09 bureau kernel: usb 1-1-port2: over-current change #243 Jan 25 21:02:09 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 21:02:09 bureau volumio[1002]: info: Reloading queue from file Jan 25 21:02:09 bureau kernel: usb 1-1-port2: over-current change #244 Jan 25 21:02:09 bureau volumio[1002]: verbose: New Socket.io Connection to 192.168.10.101:3000 from 192.168.10.100 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 25 21:02:09 bureau kernel: usb 1-1-port2: over-current change #245 Jan 25 21:02:10 bureau volumio[1002]: info: CoreStateMachine::setRepeat null single undefined Jan 25 21:02:10 bureau volumio[1002]: info: CoreStateMachine::pushState Jan 25 21:02:10 bureau volumio[1002]: info: CorePlayQueue::getTrack 0 Jan 25 21:02:10 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 21:02:10 bureau volumio[1002]: info: CoreCommandRouter::volumioPushState Jan 25 21:02:10 bureau volumio[1002]: info: CoreStateMachine::setRandom null Jan 25 21:02:10 bureau volumio[1002]: info: CoreStateMachine::pushState Jan 25 21:02:10 bureau volumio[1002]: info: CorePlayQueue::getTrack 0 Jan 25 21:02:10 bureau volumio[1002]: info: CoreCommandRouter::volumioPushState Jan 25 21:02:10 bureau volumio[1002]: info: Setting Device type: Raspberry PI Jan 25 21:02:10 bureau kernel: usb 1-1-port2: over-current change #246 Jan 25 21:02:10 bureau volumio[1002]: verbose: New Socket.io Connection to 192.168.10.101:3000 from 192.168.10.100 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 25 21:02:10 bureau volumio[1002]: info: Completed loading Core Plugins Jan 25 21:02:10 bureau volumio[1002]: info: Preparing to generate the ALSA configuration file Jan 25 21:02:10 bureau kernel: usb 1-1-port2: over-current change #247 Jan 25 21:02:10 bureau kernel: usb 1-1-port2: over-current change #248 Jan 25 21:02:10 bureau volumio-remote-updater[616]: [2026-01-25 21:02:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769371327 101 Jan 25 21:02:10 bureau volumio[1002]: 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: 3 Jan 25 21:02:10 bureau kernel: usb 1-1-port2: over-current change #249 Jan 25 21:02:11 bureau volumio[1002]: info: Discovery: adding a9714c50-d3dc-44cc-9759-8f7a1e2f027e Jan 25 21:02:11 bureau volumio[1002]: info: Discovery: Found device Cuisine Jan 25 21:02:11 bureau sudo[1215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 25 21:02:11 bureau kernel: usb 1-1-port2: over-current change #250 Jan 25 21:02:11 bureau sudo[1215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:11 bureau kernel: usb 1-1-port2: over-current change #251 Jan 25 21:02:11 bureau volumio[1002]: info: Discovery: Connecting to remote: 192.168.10.100 Jan 25 21:02:11 bureau volumio[1002]: info: Discovery: adding 305f60fc-996a-4a9c-a11d-4b5e2f41942d Jan 25 21:02:11 bureau volumio[1002]: info: Discovery: Found device Bureau Jan 25 21:02:11 bureau volumio[1002]: info: CoreCommandRouter::volumioGetState Jan 25 21:02:11 bureau volumio[1002]: info: CorePlayQueue::getTrack 0 Jan 25 21:02:11 bureau kernel: usb 1-1-port2: over-current change #252 Jan 25 21:02:11 bureau volumio[1002]: info: Discovery: this is already registered, 305f60fc-996a-4a9c-a11d-4b5e2f41942d Jan 25 21:02:11 bureau volumio[1002]: info: Discovery: Found device Bureau Jan 25 21:02:11 bureau volumio[1002]: info: CoreCommandRouter::volumioGetState Jan 25 21:02:11 bureau volumio[1002]: info: CorePlayQueue::getTrack 0 Jan 25 21:02:11 bureau volumio[1002]: info: Asound.conf file unchanged, so no further update is needed Jan 25 21:02:11 bureau volumio[1002]: info: Output device has changed, restarting MPD Jan 25 21:02:11 bureau volumio[1002]: info: Output device has changed, restarting Shairport Sync Jan 25 21:02:11 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:11 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 21:02:11 bureau kernel: usb 1-1-port2: over-current change #253 Jan 25 21:02:12 bureau sudo[1219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 21:02:12 bureau sudo[1219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:12 bureau volumio[1002]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 21:02:12 bureau volumio[1002]: info: ___________ START PLUGINS ___________ Jan 25 21:02:12 bureau sudo[1219]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:12 bureau sudo[1220]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 21:02:12 bureau sudo[1220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:12 bureau kernel: usb 1-1-port2: over-current change #254 Jan 25 21:02:12 bureau volumio[1002]: info: ControllerMpd::onStart: Initializing MPD Jan 25 21:02:12 bureau volumio[1002]: info: Creating MPD Configuration file Jan 25 21:02:12 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 21:02:12 bureau volumio[1002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 21:02:12 bureau volumio[1002]: info: [1769371332424] CoreMusicLibrary::Adding element Serveurs Média Jan 25 21:02:12 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 21:02:12 bureau volumio[1002]: info: UPNP Browser: Client initialized successfully Jan 25 21:02:12 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:12 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 21:02:12 bureau kernel: usb 1-1-port2: over-current change #255 Jan 25 21:02:12 bureau systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 25 21:02:12 bureau systemd[1]: Starting mpd.service - Music Player Daemon... Jan 25 21:02:12 bureau sudo[1228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 21:02:12 bureau sudo[1228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:12 bureau volumio[1002]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 21:02:12 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:12 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 21:02:12 bureau sudo[1228]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:12 bureau volumio[1002]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 21:02:12 bureau volumio[1002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 21:02:12 bureau volumio[1002]: info: [1769371332758] CoreMusicLibrary::Adding element Last_100 Jan 25 21:02:12 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 21:02:12 bureau kernel: usb 1-1-port2: over-current change #256 Jan 25 21:02:12 bureau volumio[1002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 21:02:12 bureau volumio[1002]: info: [1769371332776] CoreMusicLibrary::Adding element Webradio Jan 25 21:02:12 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 21:02:12 bureau sudo[1230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 21:02:12 bureau sudo[1230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:12 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 21:02:12 bureau volumio[1002]: info: Initializing BBC Radios Jan 25 21:02:12 bureau sudo[1235]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 25 21:02:12 bureau sudo[1235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 25 21:02:12 bureau sudo[1247]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 25 21:02:12 bureau sudo[1235]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:13 bureau kernel: usb 1-1-port2: over-current change #257 Jan 25 21:02:13 bureau systemd[1]: mpd.service: Deactivated successfully. Jan 25 21:02:13 bureau systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 25 21:02:13 bureau systemd[1]: mpd.socket: Deactivated successfully. Jan 25 21:02:13 bureau systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 25 21:02:13 bureau systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 25 21:02:13 bureau systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 25 21:02:13 bureau systemd[1]: Starting mpd.service - Music Player Daemon... Jan 25 21:02:13 bureau kernel: usb 1-1-port2: over-current change #258 Jan 25 21:02:13 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 21:02:13 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 21:02:13 bureau volumio[1002]: info: Creating Spotify config file Jan 25 21:02:13 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:13 bureau kernel: usb 1-1-port2: over-current change #259 Jan 25 21:02:13 bureau sudo[1254]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 25 21:02:13 bureau sudo[1254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 25 21:02:13 bureau sudo[1260]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 25 21:02:13 bureau sudo[1254]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:13 bureau kernel: usb 1-1-port2: over-current change #260 Jan 25 21:02:14 bureau kernel: usb 1-1-port2: over-current change #261 Jan 25 21:02:14 bureau kernel: usb 1-1-port2: over-current change #262 Jan 25 21:02:14 bureau kernel: usb 1-1-port2: over-current change #263 Jan 25 21:02:14 bureau kernel: usb 1-1-port2: over-current change #264 Jan 25 21:02:15 bureau kernel: usb 1-1-port2: over-current change #265 Jan 25 21:02:15 bureau kernel: usb 1-1-port2: over-current change #266 Jan 25 21:02:15 bureau kernel: usb 1-1-port2: over-current change #267 Jan 25 21:02:15 bureau kernel: usb 1-1-port2: over-current change #268 Jan 25 21:02:16 bureau kernel: usb 1-1-port2: over-current change #269 Jan 25 21:02:16 bureau systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Jan 25 21:02:16 bureau systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 21:02:16 bureau systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 21:02:16 bureau kernel: usb 1-1-port2: over-current change #270 Jan 25 21:02:16 bureau sudo[1215]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:16 bureau volumio[1157]: Starting albumart workers Jan 25 21:02:16 bureau kernel: usb 1-1-port2: over-current change #271 Jan 25 21:02:16 bureau kernel: usb 1-1-port2: over-current change #272 Jan 25 21:02:16 bureau volumio[1155]: Starting albumart workers Jan 25 21:02:17 bureau volumio[1156]: Starting albumart workers Jan 25 21:02:17 bureau kernel: usb 1-1-port2: over-current change #273 Jan 25 21:02:17 bureau kernel: usb 1-1-port2: over-current change #274 Jan 25 21:02:17 bureau volumio[1002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 21:02:17 bureau volumio[1002]: info: [1769371337473] CoreMusicLibrary::Adding element Podcast Jan 25 21:02:17 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 21:02:17 bureau volumio[1002]: Cannot find translation for source Podcast Jan 25 21:02:17 bureau volumio[1002]: info: Volumio Calling Home Jan 25 21:02:17 bureau kernel: usb 1-1-port2: over-current change #275 Jan 25 21:02:17 bureau kernel: usb 1-1-port2: over-current change #276 Jan 25 21:02:18 bureau kernel: usb 1-1-port2: over-current change #277 Jan 25 21:02:18 bureau kernel: usb 1-1-port2: over-current change #278 Jan 25 21:02:18 bureau kernel: usb 1-1-port2: over-current change #279 Jan 25 21:02:18 bureau kernel: usb 1-1-port2: over-current change #280 Jan 25 21:02:19 bureau kernel: usb 1-1-port2: over-current change #281 Jan 25 21:02:19 bureau kernel: usb 1-1-port2: over-current change #282 Jan 25 21:02:19 bureau kernel: usb 1-1-port2: over-current change #283 Jan 25 21:02:19 bureau kernel: usb 1-1-port2: over-current change #284 Jan 25 21:02:20 bureau kernel: usb 1-1-port2: over-current change #285 Jan 25 21:02:20 bureau kernel: usb 1-1-port2: over-current change #286 Jan 25 21:02:20 bureau kernel: usb 1-1-port2: over-current change #287 Jan 25 21:02:20 bureau kernel: usb 1-1-port2: over-current change #288 Jan 25 21:02:21 bureau kernel: usb 1-1-port2: over-current change #289 Jan 25 21:02:21 bureau kernel: usb 1-1-port2: over-current change #290 Jan 25 21:02:21 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 25 21:02:21 bureau kernel: usb 1-1-port2: over-current change #291 Jan 25 21:02:21 bureau volumio[1002]: info: CoreCommandRouter::volumioGetState Jan 25 21:02:21 bureau volumio[1002]: info: CorePlayQueue::getTrack 0 Jan 25 21:02:22 bureau kernel: usb 1-1-port2: over-current change #292 Jan 25 21:02:22 bureau kernel: usb 1-1-port2: over-current change #293 Jan 25 21:02:22 bureau volumio[1002]: info: Discovery: Connected to remote: 192.168.10.100 Jan 25 21:02:22 bureau kernel: usb 1-1-port2: over-current change #294 Jan 25 21:02:22 bureau kernel: usb 1-1-port2: over-current change #295 Jan 25 21:02:22 bureau volumio[1002]: info: MPD Permissions set Jan 25 21:02:23 bureau kernel: usb 1-1-port2: over-current change #296 Jan 25 21:02:23 bureau volumio[1002]: info: MPD Permissions set Jan 25 21:02:23 bureau volumio[1002]: info: Upmpdcli Daemon Started Jan 25 21:02:23 bureau kernel: usb 1-1-port2: over-current change #297 Jan 25 21:02:23 bureau kernel: usb 1-1-port2: over-current change #298 Jan 25 21:02:23 bureau kernel: usb 1-1-port2: over-current change #299 Jan 25 21:02:23 bureau volumio[1002]: info: Discovery: Disconnected from remote: 192.168.10.100 Jan 25 21:02:24 bureau kernel: usb 1-1-port2: over-current change #300 Jan 25 21:02:24 bureau volumio[1002]: info: Volumio called home Jan 25 21:02:24 bureau volumio[1002]: info: Spotify config file written Jan 25 21:02:24 bureau kernel: usb 1-1-port2: over-current change #301 Jan 25 21:02:24 bureau kernel: usb 1-1-port2: over-current change #302 Jan 25 21:02:24 bureau sudo[1321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 25 21:02:24 bureau sudo[1321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:24 bureau systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 25 21:02:24 bureau systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 25 21:02:24 bureau kernel: usb 1-1-port2: over-current change #303 Jan 25 21:02:24 bureau systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 25 21:02:24 bureau go-librespot[1329]: go-librespot daemon starting... Jan 25 21:02:24 bureau sudo[1321]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:25 bureau kernel: usb 1-1-port2: over-current change #304 Jan 25 21:02:25 bureau kernel: usb 1-1-port2: over-current change #305 Jan 25 21:02:25 bureau kernel: usb 1-1-port2: over-current change #306 Jan 25 21:02:25 bureau volumio[1002]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 25 21:02:25 bureau go-librespot[1330]: time="2026-01-25T21:02:25+01:00" level=info msg="running go-librespot 0.4.0" Jan 25 21:02:25 bureau go-librespot[1330]: time="2026-01-25T21:02:25+01:00" level=debug msg="app state loaded" Jan 25 21:02:25 bureau go-librespot[1330]: time="2026-01-25T21:02:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 25 21:02:25 bureau kernel: usb 1-1-port2: over-current change #307 Jan 25 21:02:25 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:25 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:25 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:25 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:25 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:25 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:25 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:25 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:25 bureau mpd[1264]: 2026-01-25T21:02:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 25 21:02:26 bureau systemd[1]: Started mpd.service - Music Player Daemon. Jan 25 21:02:26 bureau sudo[1230]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:26 bureau sudo[1220]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:26 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 21:02:26 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:26 bureau kernel: usb 1-1-port2: over-current change #308 Jan 25 21:02:26 bureau volumio[1002]: info: No need to fix Spotify hosts Jan 25 21:02:26 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:26 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:26 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:26 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:26 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:26 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:26 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:26 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:26 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 21:02:26 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 21:02:26 bureau kernel: usb 1-1-port2: over-current change #309 Jan 25 21:02:26 bureau go-librespot[1330]: time="2026-01-25T21:02:26+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 25 21:02:26 bureau go-librespot[1330]: time="2026-01-25T21:02:26+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 25 21:02:26 bureau go-librespot[1330]: time="2026-01-25T21:02:26+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 25 21:02:26 bureau go-librespot[1330]: time="2026-01-25T21:02:26+01:00" level=info msg="zeroconf server listening on port 34293" Jan 25 21:02:26 bureau kernel: usb 1-1-port2: over-current change #310 Jan 25 21:02:26 bureau go-librespot[1330]: time="2026-01-25T21:02:26+01:00" level=debug msg="obtained new client token: AAAasXBNXi3JVzE4Ep5XN1zXWCFc8JubxA8TrOS0byS+3XSUB/tG3cDIF9PUUszUbcdPwplIUA7G5vf4PsA7ce7CLCiec5EnqG2LstwR9F2GzT5NU2HUiEzkVfCny7M9cyEUUzn7pcbAYRPaaKO7oH1TlK+49N+o/nKZhKXpdahRwoNVNP1stxFg2rViec+lJhSEoqP63dU+26zKWBgazuGcQvioX0sMhmiO0kLKj58Yz2s1Uz67XtIVXg==" Jan 25 21:02:26 bureau go-librespot[1330]: time="2026-01-25T21:02:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 25 21:02:26 bureau go-librespot[1330]: time="2026-01-25T21:02:26+01:00" level=debug msg="completed keyexchange" Jan 25 21:02:26 bureau go-librespot[1330]: time="2026-01-25T21:02:26+01:00" level=debug msg="completed challenge" Jan 25 21:02:26 bureau kernel: usb 1-1-port2: over-current change #311 Jan 25 21:02:26 bureau go-librespot[1330]: time="2026-01-25T21:02:26+01:00" level=info msg="authenticated AP" username="le******te" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=info msg="authenticated Login5" username="le******te" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=debug msg="initializing zeroconf session" username="le******te" Jan 25 21:02:27 bureau kernel: usb 1-1-port2: over-current change #312 Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=debug msg="dealer connection opened" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=trace msg="starting accesspoint recv loop" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=trace msg="starting dealer recv loop" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=trace msg="received accesspoint ping" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=debug msg="received connection id: MDA4NzFhYjUtNmEz...RDc4RkEyMkI1NA==" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=trace msg="received accesspoint pong ack" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=debug msg="put connect state because NEW_DEVICE" Jan 25 21:02:27 bureau kernel: usb 1-1-port2: over-current change #313 Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=debug msg="update volume requested to 56360/65535" Jan 25 21:02:27 bureau volumio[1002]: info: Discovery: Connected to remote: 192.168.10.100 Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 25 21:02:27 bureau go-librespot[1330]: time="2026-01-25T21:02:27+01:00" level=trace msg="emitting websocket event: volume" Jan 25 21:02:27 bureau volumio[1002]: info: Completed starting Core Plugins Jan 25 21:02:27 bureau volumio[1002]: info: ------------------------------------------- Jan 25 21:02:27 bureau volumio[1002]: info: ----- MyVolumio plugins startup ---- Jan 25 21:02:27 bureau volumio[1002]: info: ------------------------------------------- Jan 25 21:02:27 bureau volumio[1002]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 25 21:02:27 bureau kernel: usb 1-1-port2: over-current change #314 Jan 25 21:02:27 bureau volumio[1002]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 25 21:02:27 bureau volumio[1002]: SPOTIFY: BQAmOM57BdtD6BsbzNJuLIuCLuYg0EOHHUP_94K32_pi1IhYnv8ekmIGYRh_tvZBfyWVlWcUlTbEwAqzFsacMrvQau1g0y4B8XJKNRqX5zEBeqTuSsVxl1FDLBOT5TXYupH9W5_DR4mBjCzYXux7yFtEC7MYb8mRnrUKYLudVSQZ1pxzn9mropeGmbgpLwmVmfgSv1e5DDMSy_SFBV71e-FI8rNzKIRgwjJrMhgdSMb8UzTUhaRM4V5-8CYHrFFvM35K-_m1Zad61utiWViI_oUWMgz8tah6XQnp Jan 25 21:02:27 bureau volumio[1002]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 25 21:02:27 bureau volumio[1002]: info: New Spotify access token = BQAmOM57BdtD6BsbzNJuLIuCLuYg0EOHHUP_94K32_pi1IhYnv8ekmIGYRh_tvZBfyWVlWcUlTbEwAqzFsacMrvQau1g0y4B8XJKNRqX5zEBeqTuSsVxl1FDLBOT5TXYupH9W5_DR4mBjCzYXux7yFtEC7MYb8mRnrUKYLudVSQZ1pxzn9mropeGmbgpLwmVmfgSv1e5DDMSy_SFBV71e-FI8rNzKIRgwjJrMhgdSMb8UzTUhaRM4V5-8CYHrFFvM35K-_m1Zad61utiWViI_oUWMgz8tah6XQnp Jan 25 21:02:27 bureau volumio[1002]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 25 21:02:27 bureau kernel: usb 1-1-port2: over-current change #315 Jan 25 21:02:27 bureau volumio[1002]: info: Starting Shairport Sync Jan 25 21:02:28 bureau volumio[1002]: info: Starting Shairport Sync Jan 25 21:02:28 bureau volumio[1002]: info: Starting Shairport Sync Jan 25 21:02:28 bureau sudo[1357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 21:02:28 bureau sudo[1357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:28 bureau kernel: usb 1-1-port2: over-current change #316 Jan 25 21:02:28 bureau sudo[1358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 21:02:28 bureau sudo[1360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 21:02:28 bureau sudo[1360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:28 bureau sudo[1358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:28 bureau systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 25 21:02:28 bureau systemd[1]: shairport-sync.service: Deactivated successfully. Jan 25 21:02:28 bureau systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 21:02:28 bureau systemd[1]: shairport-sync.service: Consumed 2.787s CPU time. Jan 25 21:02:28 bureau systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 21:02:28 bureau kernel: usb 1-1-port2: over-current change #317 Jan 25 21:02:28 bureau sudo[1357]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:28 bureau systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 25 21:02:28 bureau systemd[1]: shairport-sync.service: Deactivated successfully. Jan 25 21:02:28 bureau systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 21:02:28 bureau systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 21:02:28 bureau sudo[1358]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:28 bureau kernel: usb 1-1-port2: over-current change #318 Jan 25 21:02:28 bureau sudo[1360]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:28 bureau go-librespot[1330]: time="2026-01-25T21:02:28+01:00" level=debug msg="handling transfer player command from 3562dd514c0c8fd576128fb27cdfe6a7338201f2" Jan 25 21:02:28 bureau go-librespot[1330]: time="2026-01-25T21:02:28+01:00" level=debug msg="resolved context of track" uri="spotify:playlist:5gbXs0nng3BfXcMy9rG95y" Jan 25 21:02:28 bureau go-librespot[1330]: time="2026-01-25T21:02:28+01:00" level=trace msg="fetched new page 0 with 85 items (list: 85)" uri="spotify:playlist:5gbXs0nng3BfXcMy9rG95y" Jan 25 21:02:28 bureau go-librespot[1330]: time="2026-01-25T21:02:28+01:00" level=debug msg="shuffled context with seed 421914929679111510 (len: 85, keep: 2)" uri="spotify:playlist:5gbXs0nng3BfXcMy9rG95y" Jan 25 21:02:28 bureau go-librespot[1330]: time="2026-01-25T21:02:28+01:00" level=debug msg="loading track (paused: false, position: 4286ms)" uri="spotify:track:3VbGCXWRiouAq8VyMYN2MI" Jan 25 21:02:28 bureau go-librespot[1330]: time="2026-01-25T21:02:28+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 25 21:02:28 bureau go-librespot[1330]: time="2026-01-25T21:02:28+01:00" level=trace msg="emitting websocket event: will_play" Jan 25 21:02:28 bureau go-librespot[1330]: time="2026-01-25T21:02:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 415" Jan 25 21:02:28 bureau go-librespot[1330]: time="2026-01-25T21:02:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1281" Jan 25 21:02:28 bureau go-librespot[1330]: time="2026-01-25T21:02:28+01:00" level=debug msg="selected format OGG_VORBIS_320 (2112b6dd887e4369332e38a97f0b99cc89cd2e4e)" uri="spotify:track:3VbGCXWRiouAq8VyMYN2MI" Jan 25 21:02:28 bureau go-librespot[1330]: time="2026-01-25T21:02:28+01:00" level=debug msg="requested aes key for file 2112b6dd887e4369332e38a97f0b99cc89cd2e4e, gid: 3VbGCXWRiouAq8VyMYN2MI" Jan 25 21:02:28 bureau go-librespot[1330]: time="2026-01-25T21:02:28+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3VbGCXWRiouAq8VyMYN2MI" Jan 25 21:02:28 bureau kernel: usb 1-1-port2: over-current change #319 Jan 25 21:02:29 bureau volumio[1002]: error: MPD error: The expression evaluated to a falsy value: Jan 25 21:02:29 bureau volumio[1002]: assert.ok(self.idling) Jan 25 21:02:29 bureau volumio[1002]: error: The expression evaluated to a falsy value: Jan 25 21:02:29 bureau volumio[1002]: assert.ok(self.idling) Jan 25 21:02:29 bureau kernel: usb 1-1-port2: over-current change #320 Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=debug msg="fetched first chunk of 9, total size is 4250496 bytes" uri="spotify:track:3VbGCXWRiouAq8VyMYN2MI" Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=trace msg="seek to 4286ms (diff: 136ms, samples: 189012, bytes: 129843)" uri="spotify:track:3VbGCXWRiouAq8VyMYN2MI" Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=debug msg="created new output device" Jan 25 21:02:29 bureau volumio[1002]: info: MPD running with PID1264 Jan 25 21:02:29 bureau volumio[1002]: ,establishing connection Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames, PCM format = FLOAT_LE" Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=info msg="loaded track \"Love Me Do - Remastered 2009\" (paused: false, position: 4286ms, duration: 141693ms, prefetched: false)" uri="spotify:track:3VbGCXWRiouAq8VyMYN2MI" Jan 25 21:02:29 bureau kernel: usb 1-1-port2: over-current change #321 Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=trace msg="scheduling prefetch in 108s" Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=trace msg="emitting websocket event: metadata" Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=trace msg="emitting websocket event: active" Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=debug msg="sending successful reply for dealer request" Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=trace msg="emitting websocket event: playing" Jan 25 21:02:29 bureau volumio[1002]: error: updateQueue error: null Jan 25 21:02:29 bureau kernel: usb 1-1-port2: over-current change #322 Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=debug msg="fetched chunk 2/8, size: 524288" uri="spotify:track:3VbGCXWRiouAq8VyMYN2MI" Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=debug msg="fetched chunk 1/8, size: 524288" uri="spotify:track:3VbGCXWRiouAq8VyMYN2MI" Jan 25 21:02:29 bureau go-librespot[1330]: time="2026-01-25T21:02:29+01:00" level=debug msg="fetched chunk 3/8, size: 524288" uri="spotify:track:3VbGCXWRiouAq8VyMYN2MI" Jan 25 21:02:29 bureau kernel: usb 1-1-port2: over-current change #323 Jan 25 21:02:30 bureau volumio[1002]: info: Shairport-Sync Started Jan 25 21:02:30 bureau volumio[1002]: Error adding Membership: Error: addMembership EINVAL Jan 25 21:02:30 bureau volumio[1002]: info: Shairport-Sync Started Jan 25 21:02:30 bureau volumio[1002]: info: Shairport-Sync Started Jan 25 21:02:30 bureau volumio[1002]: info: go-librespot daemon successfully initialized Jan 25 21:02:30 bureau kernel: usb 1-1-port2: over-current change #324 Jan 25 21:02:30 bureau kernel: usb 1-1-port2: over-current change #325 Jan 25 21:02:30 bureau volumio[1002]: Upnp client error: Error: This socket has been ended by the other party Jan 25 21:02:30 bureau kernel: usb 1-1-port2: over-current change #326 Jan 25 21:02:30 bureau sudo[1390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 25 21:02:30 bureau volumio[1002]: error: updateQueue error: null Jan 25 21:02:30 bureau sudo[1390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:31 bureau kernel: usb 1-1-port2: over-current change #327 Jan 25 21:02:31 bureau sudo[1393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 25 21:02:31 bureau sudo[1393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:31 bureau systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 25 21:02:31 bureau volumio[1002]: info: CoreCommandRouter::volumioGetState Jan 25 21:02:31 bureau volumio[1002]: info: CorePlayQueue::getTrack 0 Jan 25 21:02:31 bureau mpd_monitor.sh[1394]: MPD Monitor Service: Starting MPD Monitor Service Jan 25 21:02:31 bureau sudo[1390]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:31 bureau systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 25 21:02:31 bureau systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 25 21:02:31 bureau systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 25 21:02:31 bureau systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 25 21:02:31 bureau mpd_monitor.sh[1399]: MPD Monitor Service: Starting MPD Monitor Service Jan 25 21:02:31 bureau sudo[1393]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:31 bureau kernel: usb 1-1-port2: over-current change #328 Jan 25 21:02:31 bureau volumio[1002]: info: Successfully started MPD Monitor Jan 25 21:02:31 bureau volumio[1002]: info: Successfully started MPD Monitor Jan 25 21:02:31 bureau kernel: usb 1-1-port2: over-current change #329 Jan 25 21:02:31 bureau volumio[1002]: SPOTIFY: User informations: {"country":"BE","display_name":"lecedekite","email":"lecedekite@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/lecedekite"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/lecedekite","id":"lecedekite","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85168ccf0bec764d9e23c497ca","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82168ccf0bec764d9e23c497ca","width":64}],"product":"premium","type":"user","uri":"spotify:user:lecedekite"} Jan 25 21:02:31 bureau volumio[1002]: info: Spotify Successfully logged in Jan 25 21:02:31 bureau kernel: usb 1-1-port2: over-current change #330 Jan 25 21:02:31 bureau volumio[1002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 21:02:31 bureau volumio[1002]: info: [1769371351810] CoreMusicLibrary::Adding element Spotify Jan 25 21:02:31 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 21:02:31 bureau volumio[1002]: Cannot find translation for source Podcast Jan 25 21:02:31 bureau volumio[1002]: Cannot find translation for source Spotify Jan 25 21:02:32 bureau kernel: usb 1-1-port2: over-current change #331 Jan 25 21:02:32 bureau kernel: usb 1-1-port2: over-current change #332 Jan 25 21:02:32 bureau kernel: usb 1-1-port2: over-current change #333 Jan 25 21:02:32 bureau kernel: usb 1-1-port2: over-current change #334 Jan 25 21:02:33 bureau kernel: usb 1-1-port2: over-current change #335 Jan 25 21:02:33 bureau volumio[1002]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 25 21:02:33 bureau kernel: usb 1-1-port2: over-current change #336 Jan 25 21:02:33 bureau volumio[1002]: info: Initializing connection to go-librespot Websocket Jan 25 21:02:33 bureau go-librespot[1330]: time="2026-01-25T21:02:33+01:00" level=debug msg="new websocket client" Jan 25 21:02:33 bureau volumio[1002]: info: Connection to go-librespot Websocket established Jan 25 21:02:33 bureau kernel: usb 1-1-port2: over-current change #337 Jan 25 21:02:33 bureau kernel: usb 1-1-port2: over-current change #338 Jan 25 21:02:34 bureau kernel: usb 1-1-port2: over-current change #339 Jan 25 21:02:34 bureau kernel: usb 1-1-port2: over-current change #340 Jan 25 21:02:34 bureau kernel: usb 1-1-port2: over-current change #341 Jan 25 21:02:34 bureau kernel: usb 1-1-port2: over-current change #342 Jan 25 21:02:35 bureau kernel: usb 1-1-port2: over-current change #343 Jan 25 21:02:35 bureau kernel: usb 1-1-port2: over-current change #344 Jan 25 21:02:35 bureau sudo[1425]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 25 21:02:35 bureau sudo[1425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:35 bureau sudo[1425]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:35 bureau kernel: usb 1-1-port2: over-current change #345 Jan 25 21:02:35 bureau sudo[1427]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 25 21:02:35 bureau sudo[1427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:35 bureau sudo[1427]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:35 bureau kernel: usb 1-1-port2: over-current change #346 Jan 25 21:02:36 bureau volumio[1002]: verbose: New Socket.io Connection to 192.168.10.101 from 192.168.10.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 25 21:02:36 bureau kernel: usb 1-1-port2: over-current change #347 Jan 25 21:02:36 bureau kernel: usb 1-1-port2: over-current change #348 Jan 25 21:02:36 bureau kernel: usb 1-1-port2: over-current change #349 Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 25 21:02:36 bureau volumio[1002]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 25 21:02:36 bureau volumio[1002]: info: Adding plugin bluetooth to MyMusic Plugins Jan 25 21:02:36 bureau volumio[1002]: info: Adding plugin multiroom to MyMusic Plugins Jan 25 21:02:36 bureau volumio[1002]: info: Adding plugin metavolumio to MyMusic Plugins Jan 25 21:02:36 bureau kernel: usb 1-1-port2: over-current change #350 Jan 25 21:02:36 bureau volumio[1002]: info: Adding plugin cd_controller to MyMusic Plugins Jan 25 21:02:36 bureau volumio[1002]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 25 21:02:37 bureau volumio[1002]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 25 21:02:37 bureau volumio[1002]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 25 21:02:37 bureau volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 25 21:02:37 bureau kernel: usb 1-1-port2: over-current change #351 Jan 25 21:02:37 bureau kernel: usb 1-1-port2: over-current change #352 Jan 25 21:02:37 bureau kernel: usb 1-1-port2: over-current change #353 Jan 25 21:02:37 bureau kernel: usb 1-1-port2: over-current change #354 Jan 25 21:02:38 bureau kernel: usb 1-1-port2: over-current change #355 Jan 25 21:02:38 bureau go-librespot[1330]: time="2026-01-25T21:02:38+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIeQbDvXgDOGT" Jan 25 21:02:38 bureau go-librespot[1330]: time="2026-01-25T21:02:38+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 25 21:02:38 bureau go-librespot[1330]: time="2026-01-25T21:02:38+01:00" level=debug msg="handling update_context player command from 3562dd514c0c8fd576128fb27cdfe6a7338201f2" Jan 25 21:02:38 bureau go-librespot[1330]: time="2026-01-25T21:02:38+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 25 21:02:38 bureau go-librespot[1330]: time="2026-01-25T21:02:38+01:00" level=debug msg="sending successful reply for dealer request" Jan 25 21:02:38 bureau kernel: usb 1-1-port2: over-current change #356 Jan 25 21:02:38 bureau kernel: usb 1-1-port2: over-current change #357 Jan 25 21:02:38 bureau kernel: usb 1-1-port2: over-current change #358 Jan 25 21:02:39 bureau kernel: usb 1-1-port2: over-current change #359 Jan 25 21:02:39 bureau kernel: usb 1-1-port2: over-current change #360 Jan 25 21:02:39 bureau kernel: usb 1-1-port2: over-current change #361 Jan 25 21:02:40 bureau kernel: usb 1-1-port2: over-current change #362 Jan 25 21:02:40 bureau kernel: usb 1-1-port2: over-current change #363 Jan 25 21:02:40 bureau kernel: usb 1-1-port2: over-current change #364 Jan 25 21:02:40 bureau kernel: usb 1-1-port2: over-current change #365 Jan 25 21:02:41 bureau kernel: usb 1-1-port2: over-current change #366 Jan 25 21:02:41 bureau kernel: usb 1-1-port2: over-current change #367 Jan 25 21:02:41 bureau kernel: usb 1-1-port2: over-current change #368 Jan 25 21:02:41 bureau kernel: usb 1-1-port2: over-current change #369 Jan 25 21:02:42 bureau kernel: usb 1-1-port2: over-current change #370 Jan 25 21:02:42 bureau go-librespot[1330]: time="2026-01-25T21:02:42+01:00" level=debug msg="fetched chunk 4/8, size: 524288" uri="spotify:track:3VbGCXWRiouAq8VyMYN2MI" Jan 25 21:02:42 bureau kernel: usb 1-1-port2: over-current change #371 Jan 25 21:02:42 bureau kernel: usb 1-1-port2: over-current change #372 Jan 25 21:02:42 bureau kernel: usb 1-1-port2: over-current change #373 Jan 25 21:02:43 bureau kernel: usb 1-1-port2: over-current change #374 Jan 25 21:02:43 bureau kernel: usb 1-1-port2: over-current change #375 Jan 25 21:02:43 bureau kernel: usb 1-1-port2: over-current change #376 Jan 25 21:02:43 bureau kernel: usb 1-1-port2: over-current change #377 Jan 25 21:02:43 bureau go-librespot[1330]: time="2026-01-25T21:02:43+01:00" level=debug msg="skipping dealer message" uri=playback-settings/content-settings-update Jan 25 21:02:43 bureau go-librespot[1330]: time="2026-01-25T21:02:43+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 162" Jan 25 21:02:44 bureau kernel: usb 1-1-port2: over-current change #378 Jan 25 21:02:44 bureau kernel: usb 1-1-port2: over-current change #379 Jan 25 21:02:44 bureau kernel: usb 1-1-port2: over-current change #380 Jan 25 21:02:44 bureau kernel: usb 1-1-port2: over-current change #381 Jan 25 21:02:44 bureau go-librespot[1330]: time="2026-01-25T21:02:44+01:00" level=debug msg="handling play player command from 3562dd514c0c8fd576128fb27cdfe6a7338201f2" Jan 25 21:02:44 bureau go-librespot[1330]: time="2026-01-25T21:02:44+01:00" level=debug msg="resolved context of track" uri="spotify:playlist:2V5fJpFfyi7lbDFKoFJBLi" Jan 25 21:02:44 bureau go-librespot[1330]: time="2026-01-25T21:02:44+01:00" level=trace msg="fetched new page 0 with 155 items (list: 155)" uri="spotify:playlist:2V5fJpFfyi7lbDFKoFJBLi" Jan 25 21:02:44 bureau go-librespot[1330]: time="2026-01-25T21:02:44+01:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:4j0511hrKS9wpThoR2qpTy" Jan 25 21:02:44 bureau go-librespot[1330]: time="2026-01-25T21:02:44+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 25 21:02:44 bureau go-librespot[1330]: time="2026-01-25T21:02:44+01:00" level=trace msg="emitting websocket event: will_play" Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=warning msg="original track has no formats, alternatives have a total of 4" uri="spotify:track:4j0511hrKS9wpThoR2qpTy" Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=debug msg="selected format OGG_VORBIS_320 (0ceb913302de5ceda09e6ea8db41bff602360186)" uri="spotify:track:4j0511hrKS9wpThoR2qpTy" Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=debug msg="requested aes key for file 0ceb913302de5ceda09e6ea8db41bff602360186, gid: 4j0511hrKS9wpThoR2qpTy" Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:4j0511hrKS9wpThoR2qpTy" Jan 25 21:02:45 bureau kernel: usb 1-1-port2: over-current change #382 Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=debug msg="fetched first chunk of 33, total size is 16912692 bytes" uri="spotify:track:4j0511hrKS9wpThoR2qpTy" Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=trace msg="seek to 1ms (diff: -151ms, samples: 44, bytes: 5966)" uri="spotify:track:4j0511hrKS9wpThoR2qpTy" Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=info msg="loaded track \"The House Of House - Original Mix\" (paused: false, position: 1ms, duration: 458240ms, prefetched: false)" uri="spotify:track:4j0511hrKS9wpThoR2qpTy" Jan 25 21:02:45 bureau kernel: usb 1-1-port2: over-current change #383 Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=trace msg="scheduling prefetch in 428s" Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=trace msg="emitting websocket event: metadata" Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=debug msg="sending successful reply for dealer request" Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=trace msg="emitting websocket event: playing" Jan 25 21:02:45 bureau kernel: usb 1-1-port2: over-current change #384 Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=debug msg="fetched chunk 3/32, size: 524288" uri="spotify:track:4j0511hrKS9wpThoR2qpTy" Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=debug msg="fetched chunk 1/32, size: 524288" uri="spotify:track:4j0511hrKS9wpThoR2qpTy" Jan 25 21:02:45 bureau kernel: usb 1-1-port2: over-current change #385 Jan 25 21:02:45 bureau go-librespot[1330]: time="2026-01-25T21:02:45+01:00" level=debug msg="fetched chunk 2/32, size: 524288" uri="spotify:track:4j0511hrKS9wpThoR2qpTy" Jan 25 21:02:46 bureau kernel: usb 1-1-port2: over-current change #386 Jan 25 21:02:46 bureau kernel: usb 1-1-port2: over-current change #387 Jan 25 21:02:46 bureau kernel: usb 1-1-port2: over-current change #388 Jan 25 21:02:46 bureau kernel: usb 1-1-port2: over-current change #389 Jan 25 21:02:47 bureau kernel: usb 1-1-port2: over-current change #390 Jan 25 21:02:47 bureau volumio[1002]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 25 21:02:47 bureau volumio[1002]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 25 21:02:47 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 21:02:47 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 21:02:47 bureau volumio[1002]: info: Starting MyVolumio Remote Streaming Endpoints Jan 25 21:02:47 bureau kernel: usb 1-1-port2: over-current change #391 Jan 25 21:02:47 bureau kernel: usb 1-1-port2: over-current change #392 Jan 25 21:02:47 bureau volumio[1002]: info: MyVolumio login type: Token Jan 25 21:02:47 bureau kernel: usb 1-1-port2: over-current change #393 Jan 25 21:02:48 bureau volumio[1002]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 25 21:02:48 bureau volumio[1002]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 25 21:02:48 bureau kernel: usb 1-1-port2: over-current change #394 Jan 25 21:02:48 bureau kernel: usb 1-1-port2: over-current change #395 Jan 25 21:02:48 bureau kernel: usb 1-1-port2: over-current change #396 Jan 25 21:02:49 bureau kernel: usb 1-1-port2: over-current change #397 Jan 25 21:02:49 bureau kernel: usb 1-1-port2: over-current change #398 Jan 25 21:02:49 bureau kernel: usb 1-1-port2: over-current change #399 Jan 25 21:02:49 bureau kernel: usb 1-1-port2: over-current change #400 Jan 25 21:02:50 bureau kernel: usb 1-1-port2: over-current change #401 Jan 25 21:02:50 bureau kernel: usb 1-1-port2: over-current change #402 Jan 25 21:02:50 bureau kernel: usb 1-1-port2: over-current change #403 Jan 25 21:02:50 bureau kernel: usb 1-1-port2: over-current change #404 Jan 25 21:02:51 bureau kernel: usb 1-1-port2: over-current change #405 Jan 25 21:02:51 bureau kernel: usb 1-1-port2: over-current change #406 Jan 25 21:02:51 bureau kernel: usb 1-1-port2: over-current change #407 Jan 25 21:02:51 bureau kernel: usb 1-1-port2: over-current change #408 Jan 25 21:02:52 bureau kernel: usb 1-1-port2: over-current change #409 Jan 25 21:02:52 bureau kernel: usb 1-1-port2: over-current change #410 Jan 25 21:02:52 bureau kernel: usb 1-1-port2: over-current change #411 Jan 25 21:02:52 bureau kernel: usb 1-1-port2: over-current change #412 Jan 25 21:02:53 bureau kernel: usb 1-1-port2: over-current change #413 Jan 25 21:02:53 bureau kernel: usb 1-1-port2: over-current change #414 Jan 25 21:02:53 bureau kernel: usb 1-1-port2: over-current change #415 Jan 25 21:02:53 bureau kernel: usb 1-1-port2: over-current change #416 Jan 25 21:02:54 bureau kernel: usb 1-1-port2: over-current change #417 Jan 25 21:02:54 bureau kernel: usb 1-1-port2: over-current change #418 Jan 25 21:02:54 bureau kernel: usb 1-1-port2: over-current change #419 Jan 25 21:02:54 bureau kernel: usb 1-1-port2: over-current change #420 Jan 25 21:02:55 bureau kernel: usb 1-1-port2: over-current change #421 Jan 25 21:02:55 bureau volumio[1002]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 25 21:02:55 bureau volumio[1002]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 25 21:02:55 bureau volumio[1002]: info: Streaming services startup Jan 25 21:02:55 bureau volumio[1002]: info: Starting Streaming Daemon Jan 25 21:02:55 bureau kernel: usb 1-1-port2: over-current change #422 Jan 25 21:02:55 bureau volumio[1002]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 25 21:02:55 bureau kernel: usb 1-1-port2: over-current change #423 Jan 25 21:02:55 bureau sudo[1469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 25 21:02:55 bureau sudo[1469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:02:55 bureau sudo[1469]: pam_unix(sudo:session): session closed for user root Jan 25 21:02:55 bureau kernel: usb 1-1-port2: over-current change #424 Jan 25 21:02:56 bureau kernel: usb 1-1-port2: over-current change #425 Jan 25 21:02:56 bureau volumio[1002]: info: Getting Spotify volume Jan 25 21:02:56 bureau kernel: usb 1-1-port2: over-current change #426 Jan 25 21:02:56 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 21:02:56 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 21:02:56 bureau kernel: usb 1-1-port2: over-current change #427 Jan 25 21:02:56 bureau volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 25 21:02:56 bureau kernel: usb 1-1-port2: over-current change #428 Jan 25 21:02:57 bureau volumio[1002]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:2V5fJpFfyi7lbDFKoFJBLi","uri":"spotify:track:4j0511hrKS9wpThoR2qpTy","play_origin":"playlist/ondemand"}} Jan 25 21:02:57 bureau volumio[1002]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4j0511hrKS9wpThoR2qpTy","name":"The House Of House - Original Mix","artist_names":["Cherrymoon Trax"],"album_name":"Bonzai Records - All The Full Length Hits \u0026 More","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02fb2cba8ede4c7f3c61e193aa","position":1,"duration":458240,"release_date":"year:2010 month:12 day:21","track_number":20,"disc_number":1}} Jan 25 21:02:57 bureau kernel: usb 1-1-port2: over-current change #429 Jan 25 21:02:57 bureau volumio[1002]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:2V5fJpFfyi7lbDFKoFJBLi","uri":"spotify:track:4j0511hrKS9wpThoR2qpTy","resume":false,"play_origin":"playlist/ondemand"}} Jan 25 21:02:57 bureau go-librespot[1330]: time="2026-01-25T21:02:57+01:00" level=trace msg="sent dealer ping" Jan 25 21:02:57 bureau go-librespot[1330]: time="2026-01-25T21:02:57+01:00" level=trace msg="received dealer pong" Jan 25 21:02:57 bureau volumio[1002]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 21:02:57 bureau volumio[1002]: TypeError: Cannot read properties of undefined (reading 'service') Jan 25 21:02:57 bureau volumio[1002]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Jan 25 21:02:57 bureau volumio[1002]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:272:18) Jan 25 21:02:57 bureau volumio[1002]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Jan 25 21:02:57 bureau volumio[1002]: at WebSocket.emit (node:events:514:28) Jan 25 21:02:57 bureau volumio[1002]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jan 25 21:02:57 bureau volumio[1002]: at Receiver.emit (node:events:514:28) Jan 25 21:02:57 bureau volumio[1002]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Jan 25 21:02:57 bureau volumio[1002]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jan 25 21:02:57 bureau volumio[1002]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jan 25 21:02:57 bureau volumio[1002]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jan 25 21:02:57 bureau volumio[1002]: at writeOrBuffer (node:internal/streams/writable:399:12) Jan 25 21:02:57 bureau volumio[1002]: at _write (node:internal/streams/writable:340:10) Jan 25 21:02:57 bureau volumio[1002]: at Writable.write (node:internal/streams/writable:344:10) Jan 25 21:02:57 bureau volumio[1002]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Jan 25 21:02:57 bureau volumio[1002]: at Socket.emit (node:events:514:28) Jan 25 21:02:57 bureau volumio[1002]: at addChunk (node:internal/streams/readable:343:12) Jan 25 21:02:57 bureau volumio[1002]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 21:02:57 bureau kernel: usb 1-1-port2: over-current change #430 Jan 25 21:02:57 bureau kernel: usb 1-1-port2: over-current change #431 Jan 25 21:02:57 bureau kernel: usb 1-1-port2: over-current change #432 Jan 25 21:02:58 bureau kernel: usb 1-1-port2: over-current change #433 Jan 25 21:02:58 bureau kernel: usb 1-1-port2: over-current change #434 Jan 25 21:02:58 bureau kernel: usb 1-1-port2: over-current change #435 Jan 25 21:02:59 bureau kernel: usb 1-1-port2: over-current change #436 Jan 25 21:02:59 bureau kernel: usb 1-1-port2: over-current change #437 Jan 25 21:02:59 bureau kernel: usb 1-1-port2: over-current change #438 Jan 25 21:02:59 bureau kernel: usb 1-1-port2: over-current change #439 Jan 25 21:02:59 bureau go-librespot[1330]: time="2026-01-25T21:02:59+01:00" level=debug msg="fetched chunk 4/32, size: 524288" uri="spotify:track:4j0511hrKS9wpThoR2qpTy" Jan 25 21:03:00 bureau kernel: usb 1-1-port2: over-current change #440 Jan 25 21:03:00 bureau kernel: usb 1-1-port2: over-current change #441 Jan 25 21:03:00 bureau kernel: usb 1-1-port2: over-current change #442 Jan 25 21:03:00 bureau kernel: usb 1-1-port2: over-current change #443 Jan 25 21:03:01 bureau kernel: usb 1-1-port2: over-current change #444 Jan 25 21:03:01 bureau kernel: usb 1-1-port2: over-current change #445 Jan 25 21:03:01 bureau kernel: usb 1-1-port2: over-current change #446 Jan 25 21:03:01 bureau kernel: usb 1-1-port2: over-current change #447 Jan 25 21:03:02 bureau kernel: usb 1-1-port2: over-current change #448 Jan 25 21:03:02 bureau kernel: usb 1-1-port2: over-current change #449 Jan 25 21:03:02 bureau kernel: usb 1-1-port2: over-current change #450 Jan 25 21:03:02 bureau kernel: usb 1-1-port2: over-current change #451 Jan 25 21:03:03 bureau kernel: usb 1-1-port2: over-current change #452 Jan 25 21:03:03 bureau kernel: usb 1-1-port2: over-current change #453 Jan 25 21:03:03 bureau kernel: usb 1-1-port2: over-current change #454 Jan 25 21:03:03 bureau kernel: usb 1-1-port2: over-current change #455 Jan 25 21:03:04 bureau kernel: usb 1-1-port2: over-current change #456 Jan 25 21:03:04 bureau kernel: usb 1-1-port2: over-current change #457 Jan 25 21:03:04 bureau kernel: usb 1-1-port2: over-current change #458 Jan 25 21:03:04 bureau kernel: usb 1-1-port2: over-current change #459 Jan 25 21:03:05 bureau kernel: usb 1-1-port2: over-current change #460 Jan 25 21:03:05 bureau kernel: usb 1-1-port2: over-current change #461 Jan 25 21:03:05 bureau kernel: usb 1-1-port2: over-current change #462 Jan 25 21:03:05 bureau kernel: usb 1-1-port2: over-current change #463 Jan 25 21:03:06 bureau kernel: usb 1-1-port2: over-current change #464 Jan 25 21:03:06 bureau upmpdcli[1504]: writing RSA key Jan 25 21:03:06 bureau kernel: usb 1-1-port2: over-current change #465 Jan 25 21:03:06 bureau sudo[1511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-25 21:02' Jan 25 21:03:06 bureau sudo[1511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 21:03:06 bureau kernel: usb 1-1-port2: over-current change #466 Jan 25 21:03:06 bureau kernel: usb 1-1-port2: over-current change #467 Jan 25 21:03:07 bureau kernel: usb 1-1-port2: over-current change #468 PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"