oct. 11 12:26:00 chatbry kernel: usb 1-1-port2: over-current change #16 oct. 11 12:26:00 chatbry kernel: usb 1-1.1-port3: over-current change #18 oct. 11 12:26:00 chatbry volumio[1135]: info: Starting UPNP Browser oct. 11 12:26:00 chatbry volumio[1135]: info: Loading plugin "alarm-clock"... oct. 11 12:26:00 chatbry kernel: usb 1-1-port3: over-current change #16 oct. 11 12:26:00 chatbry kernel: usb 1-1.1-port2: over-current change #19 oct. 11 12:26:00 chatbry kernel: usb 1-1-port4: over-current change #16 oct. 11 12:26:00 chatbry kernel: usb 1-1.1-port3: over-current change #19 oct. 11 12:26:00 chatbry volumio[1135]: info: Loading plugin "airplay_emulation"... oct. 11 12:26:00 chatbry volumio[1135]: info: Starting Shairport Sync oct. 11 12:26:00 chatbry volumio[1135]: info: Loading plugin "last_100"... oct. 11 12:26:00 chatbry volumio[1135]: info: Loading plugin "webradio"... oct. 11 12:26:00 chatbry kernel: usb 1-1-port2: over-current change #17 oct. 11 12:26:00 chatbry volumio[1135]: info: Loading plugin "i2s_dacs"... oct. 11 12:26:00 chatbry volumio[1135]: info: I2S DAC not set, start Auto-detection oct. 11 12:26:00 chatbry volumio[1135]: info: Loading plugin "volumiodiscovery"... oct. 11 12:26:00 chatbry volumio[1135]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. oct. 11 12:26:00 chatbry volumio[1135]: *** WARNING *** Please fix your application to use the native API of Avahi! oct. 11 12:26:00 chatbry volumio[1135]: *** WARNING *** For more information see oct. 11 12:26:00 chatbry volumio[1135]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. oct. 11 12:26:00 chatbry volumio[1135]: *** WARNING *** Please fix your application to use the native API of Avahi! oct. 11 12:26:00 chatbry volumio[1135]: *** WARNING *** For more information see oct. 11 12:26:00 chatbry node[1135]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. oct. 11 12:26:00 chatbry node[1135]: *** WARNING *** Please fix your application to use the native API of Avahi! oct. 11 12:26:00 chatbry node[1135]: *** WARNING *** For more information see oct. 11 12:26:00 chatbry node[1135]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. oct. 11 12:26:00 chatbry node[1135]: *** WARNING *** Please fix your application to use the native API of Avahi! oct. 11 12:26:00 chatbry node[1135]: *** WARNING *** For more information see oct. 11 12:26:00 chatbry volumio[1135]: info: Applying required configuration parameters for plugin volumiodiscovery oct. 11 12:26:00 chatbry volumio[1135]: info: Discovery: Started advertising with name: Chatbry oct. 11 12:26:01 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback oct. 11 12:26:01 chatbry volumio[1135]: info: Loading plugin "autostart"... oct. 11 12:26:01 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 9 using dwc_otg oct. 11 12:26:01 chatbry kernel: usb 1-1.1.2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 oct. 11 12:26:01 chatbry kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 oct. 11 12:26:01 chatbry kernel: usb 1-1.1.2: Product: SanDisk 3.2Gen1 oct. 11 12:26:01 chatbry kernel: usb 1-1.1.2: Manufacturer: USB oct. 11 12:26:01 chatbry kernel: usb 1-1.1.2: SerialNumber: 01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476 oct. 11 12:26:01 chatbry kernel: usb-storage 1-1.1.2:1.0: USB Mass Storage device detected oct. 11 12:26:01 chatbry kernel: scsi host0: usb-storage 1-1.1.2:1.0 oct. 11 12:26:01 chatbry volumio[1135]: info: Applying required configuration parameters for plugin autostart oct. 11 12:26:01 chatbry volumio[1135]: info: AutoStart - onVolumioStart - read config.json oct. 11 12:26:01 chatbry volumio[1135]: info: Loading plugin "now_playing"... oct. 11 12:26:01 chatbry kernel: usb 1-1.1-port2: over-current change #20 oct. 11 12:26:01 chatbry kernel: usb 1-1-port2: over-current change #18 oct. 11 12:26:02 chatbry kernel: usb 1-1.1-port3: over-current change #20 oct. 11 12:26:02 chatbry kernel: usb 1-1-port3: over-current change #17 oct. 11 12:26:02 chatbry kernel: usb 1-1.1-port2: over-current change #21 oct. 11 12:26:02 chatbry kernel: usb 1-1-port4: over-current change #17 oct. 11 12:26:02 chatbry kernel: usb 1-1.1-port3: over-current change #21 oct. 11 12:26:02 chatbry kernel: usb 1-1-port2: over-current change #19 oct. 11 12:26:02 chatbry kernel: usb 1-1-port3: over-current change #18 oct. 11 12:26:03 chatbry kernel: usb 1-1-port4: over-current change #18 oct. 11 12:26:03 chatbry kernel: usb 1-1-port2: over-current change #20 oct. 11 12:26:03 chatbry kernel: usb 1-1.1-port2: over-current change #22 oct. 11 12:26:03 chatbry kernel: usb 1-1.1.2: USB disconnect, device number 9 oct. 11 12:26:03 chatbry kernel: usb 1-1-port3: over-current change #19 oct. 11 12:26:03 chatbry kernel: usb 1-1.1-port3: over-current change #22 oct. 11 12:26:03 chatbry kernel: usb 1-1-port4: over-current change #19 oct. 11 12:26:04 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 10 using dwc_otg oct. 11 12:26:04 chatbry kernel: usb 1-1.1.2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 oct. 11 12:26:04 chatbry kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 oct. 11 12:26:04 chatbry kernel: usb 1-1.1.2: Product: SanDisk 3.2Gen1 oct. 11 12:26:04 chatbry kernel: usb 1-1.1.2: Manufacturer: USB oct. 11 12:26:04 chatbry kernel: usb 1-1.1.2: SerialNumber: 01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476 oct. 11 12:26:04 chatbry kernel: usb-storage 1-1.1.2:1.0: USB Mass Storage device detected oct. 11 12:26:04 chatbry kernel: scsi host0: usb-storage 1-1.1.2:1.0 oct. 11 12:26:04 chatbry kernel: usb 1-1.1-port2: over-current change #23 oct. 11 12:26:04 chatbry kernel: usb 1-1-port2: over-current change #21 oct. 11 12:26:04 chatbry kernel: usb 1-1.1-port3: over-current change #23 oct. 11 12:26:04 chatbry kernel: usb 1-1-port3: over-current change #20 oct. 11 12:26:04 chatbry kernel: usb 1-1-port4: over-current change #20 oct. 11 12:26:05 chatbry kernel: usb 1-1.1-port2: over-current change #24 oct. 11 12:26:05 chatbry kernel: usb 1-1-port2: over-current change #22 oct. 11 12:26:05 chatbry kernel: usb 1-1.1-port3: over-current change #24 oct. 11 12:26:05 chatbry kernel: usb 1-1-port3: over-current change #21 oct. 11 12:26:05 chatbry volumio[1135]: info: Loading plugin "outputs"... oct. 11 12:26:05 chatbry volumio[1135]: info: Loading plugin "albumart"... oct. 11 12:26:05 chatbry volumio[1135]: info: Plugin example_plugin is not enabled oct. 11 12:26:05 chatbry volumio[1135]: info: Loading plugin "inputs"... oct. 11 12:26:05 chatbry volumio[1135]: info: Loading plugin "updater_comm"... oct. 11 12:26:05 chatbry kernel: usb 1-1-port4: over-current change #21 oct. 11 12:26:05 chatbry kernel: usb 1-1-port2: over-current change #23 oct. 11 12:26:05 chatbry systemd[1]: systemd-fsckd.service: Deactivated successfully. oct. 11 12:26:06 chatbry volumio[1135]: info: Plugin mpdemulation is not enabled oct. 11 12:26:06 chatbry volumio[1135]: info: Loading plugin "rest_api"... oct. 11 12:26:06 chatbry kernel: usb 1-1.1-port2: over-current change #25 oct. 11 12:26:06 chatbry volumio[1135]: info: Loading plugin "websocket"... oct. 11 12:26:06 chatbry kernel: usb 1-1-port3: over-current change #22 oct. 11 12:26:06 chatbry volumio-remote-updater[637]: [2026-10-11 12:26:06] [connect] Successful connection oct. 11 12:26:06 chatbry volumio[1135]: info: Starting Socket.io Server version 1.7.4 oct. 11 12:26:06 chatbry volumio[1135]: info: Loading plugin "Systeminfo"... oct. 11 12:26:06 chatbry kernel: usb 1-1.1.2: USB disconnect, device number 10 oct. 11 12:26:06 chatbry kernel: usb 1-1-port4: over-current change #22 oct. 11 12:26:06 chatbry kernel: usb 1-1.1-port3: over-current change #25 oct. 11 12:26:06 chatbry kernel: usb 1-1-port2: over-current change #24 oct. 11 12:26:06 chatbry kernel: usb 1-1.1-port2: over-current change #26 oct. 11 12:26:06 chatbry kernel: usb 1-1-port3: over-current change #23 oct. 11 12:26:06 chatbry kernel: usb 1-1.1-port2: over-current condition oct. 11 12:26:06 chatbry kernel: usb 1-1.1-port2: over-current change #27 oct. 11 12:26:07 chatbry kernel: usb 1-1-port2: over-current change #25 oct. 11 12:26:07 chatbry kernel: usb 1-1.1-port3: over-current change #26 oct. 11 12:26:07 chatbry kernel: usb 1-1-port3: over-current change #24 oct. 11 12:26:07 chatbry kernel: usb 1-1.1-port2: over-current change #28 oct. 11 12:26:07 chatbry volumio[1235]: Forking 3 albumart workers oct. 11 12:26:07 chatbry kernel: usb 1-1-port4: over-current change #23 oct. 11 12:26:07 chatbry kernel: usb 1-1.1-port3: over-current change #27 oct. 11 12:26:07 chatbry kernel: usb 1-1-port2: over-current change #26 oct. 11 12:26:07 chatbry kernel: usb 1-1.1-port2: over-current change #29 oct. 11 12:26:08 chatbry kernel: usb 1-1-port2: over-current change #27 oct. 11 12:26:08 chatbry kernel: usb 1-1.1-port3: over-current change #28 oct. 11 12:26:08 chatbry kernel: usb 1-1-port3: over-current change #25 oct. 11 12:26:08 chatbry kernel: usb 1-1.1-port2: over-current change #30 oct. 11 12:26:08 chatbry kernel: usb 1-1-port4: over-current change #24 oct. 11 12:26:08 chatbry kernel: usb 1-1.1-port2: over-current change #31 oct. 11 12:26:08 chatbry volumio[1135]: info: Loading i18n strings for locale fr oct. 11 12:26:08 chatbry kernel: usb 1-1-port2: over-current change #28 oct. 11 12:26:08 chatbry volumio[1135]: Updating browse sources language oct. 11 12:26:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources oct. 11 12:26:08 chatbry kernel: usb 1-1.1-port3: over-current change #29 oct. 11 12:26:09 chatbry kernel: usb 1-1-port3: over-current change #26 oct. 11 12:26:09 chatbry kernel: usb 1-1.1-port2: over-current change #32 oct. 11 12:26:09 chatbry kernel: usb 1-1-port4: over-current change #25 oct. 11 12:26:09 chatbry kernel: usb 1-1-port2: over-current change #29 oct. 11 12:26:09 chatbry kernel: usb 1-1.1-port2: over-current change #33 oct. 11 12:26:09 chatbry systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. oct. 11 12:26:09 chatbry systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. oct. 11 12:26:09 chatbry kernel: usb 1-1-port3: over-current change #27 oct. 11 12:26:09 chatbry systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. oct. 11 12:26:09 chatbry kernel: usb 1-1.1-port3: over-current change #30 oct. 11 12:26:09 chatbry upmpdcli[1289]: Could not open config: /tmp/upmpdcli.conf oct. 11 12:26:09 chatbry systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE oct. 11 12:26:09 chatbry systemd[1]: upmpdcli.service: Failed with result 'exit-code'. oct. 11 12:26:09 chatbry kernel: usb 1-1-port4: over-current change #26 oct. 11 12:26:09 chatbry kernel: usb 1-1.1-port2: over-current change #34 oct. 11 12:26:10 chatbry kernel: usb 1-1-port2: over-current change #30 oct. 11 12:26:10 chatbry kernel: usb 1-1.1-port3: over-current change #31 oct. 11 12:26:10 chatbry kernel: usb 1-1-port3: over-current change #28 oct. 11 12:26:10 chatbry kernel: usb 1-1.1-port2: over-current change #35 oct. 11 12:26:10 chatbry kernel: usb 1-1-port4: over-current change #27 oct. 11 12:26:10 chatbry kernel: usb 1-1.1-port3: over-current change #32 oct. 11 12:26:10 chatbry systemd[1]: systemd-hostnamed.service: Deactivated successfully. oct. 11 12:26:10 chatbry kernel: usb 1-1-port2: over-current change #31 oct. 11 12:26:10 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam oct. 11 12:26:10 chatbry volumio[1135]: info: CoreCommandRouter::initPlayerControls oct. 11 12:26:10 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:11 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:11 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:11 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:11 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:11 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:11 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:11 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:11 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam oct. 11 12:26:11 chatbry kernel: usb 1-1-port3: over-current change #29 oct. 11 12:26:11 chatbry kernel: usb 1-1.1-port2: over-current change #36 oct. 11 12:26:11 chatbry volumio[1135]: Express server listening on port 3000 oct. 11 12:26:11 chatbry volumio[1135]: [Metrics] WebUI: 21s 135.40ms oct. 11 12:26:11 chatbry kernel: usb 1-1-port3: over-current condition oct. 11 12:26:11 chatbry kernel: usb 1-1-port4: over-current change #28 oct. 11 12:26:11 chatbry kernel: usb 1-1.1-port3: over-current change #33 oct. 11 12:26:11 chatbry volumio[1135]: info: CoreStateMachine::resetVolumioState oct. 11 12:26:11 chatbry volumio[1135]: info: CoreStateMachine::getcurrentVolume oct. 11 12:26:11 chatbry volumio[1135]: info: CoreCommandRouter::volumioRetrievevolume oct. 11 12:26:11 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:26:11 chatbry kernel: usb 1-1-port2: over-current change #32 oct. 11 12:26:11 chatbry kernel: usb 1-1.1-port2: over-current change #37 oct. 11 12:26:11 chatbry kernel: usb 1-1-port3: over-current change #30 oct. 11 12:26:11 chatbry kernel: usb 1-1.1-port3: over-current change #34 oct. 11 12:26:11 chatbry sudo[1298]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 oct. 11 12:26:11 chatbry sudo[1298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:11 chatbry kernel: usb 1-1-port4: over-current change #29 oct. 11 12:26:11 chatbry sudo[1298]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:11 chatbry sudo[1299]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 oct. 11 12:26:11 chatbry sudo[1299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:12 chatbry sudo[1299]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:12 chatbry kernel: usb 1-1.1-port2: over-current change #38 oct. 11 12:26:12 chatbry volumio[1135]: info: Volumio Network Manager: Network status updated: 1 oct. 11 12:26:12 chatbry kernel: usb 1-1-port2: over-current change #33 oct. 11 12:26:12 chatbry kernel: usb 1-1.1-port3: over-current change #35 oct. 11 12:26:12 chatbry kernel: usb 1-1-port3: over-current change #31 oct. 11 12:26:12 chatbry kernel: usb 1-1-port3: over-current condition oct. 11 12:26:12 chatbry kernel: usb 1-1-port4: over-current change #30 oct. 11 12:26:12 chatbry kernel: usb 1-1.1-port2: over-current change #39 oct. 11 12:26:12 chatbry kernel: usb 1-1-port2: over-current change #34 oct. 11 12:26:12 chatbry volumio[1135]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.19 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 oct. 11 12:26:13 chatbry kernel: usb 1-1.1-port3: over-current change #36 oct. 11 12:26:13 chatbry kernel: usb 1-1-port3: over-current change #32 oct. 11 12:26:13 chatbry kernel: usb 1-1.1-port3: over-current condition oct. 11 12:26:13 chatbry kernel: usb 1-1.1-port2: over-current change #40 oct. 11 12:26:13 chatbry volumio[1135]: info: VolumeController:: Volume=60 Mute =false oct. 11 12:26:13 chatbry kernel: usb 1-1-port4: over-current change #31 oct. 11 12:26:13 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:13 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:13 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo oct. 11 12:26:13 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:13 chatbry volumio[1135]: info: CoreStateMachine::updateTrackBlock oct. 11 12:26:13 chatbry volumio[1135]: info: CorePlayQueue::getTrackBlock oct. 11 12:26:13 chatbry volumio[1135]: info: CoreCommandRouter::volumioRetrievevolume oct. 11 12:26:13 chatbry kernel: usb 1-1.1-port3: over-current change #37 oct. 11 12:26:13 chatbry kernel: usb 1-1-port2: over-current change #35 oct. 11 12:26:13 chatbry volumio[1135]: info: Reloading queue from file oct. 11 12:26:13 chatbry kernel: usb 1-1.1-port2: over-current change #41 oct. 11 12:26:13 chatbry kernel: usb 1-1-port3: over-current change #33 oct. 11 12:26:13 chatbry volumio[1135]: verbose: New Socket.io Connection to chatbry.local from 192.168.1.19 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 oct. 11 12:26:13 chatbry volumio[1135]: info: CoreStateMachine::setRepeat false single undefined oct. 11 12:26:13 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:13 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:13 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo oct. 11 12:26:13 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:13 chatbry kernel: usb 1-1.1-port3: over-current change #38 oct. 11 12:26:13 chatbry volumio[1135]: info: CoreStateMachine::setRandom false oct. 11 12:26:13 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:13 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:13 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo oct. 11 12:26:13 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:13 chatbry volumio[1135]: info: Setting Device type: Raspberry PI oct. 11 12:26:14 chatbry kernel: usb 1-1-port4: over-current change #32 oct. 11 12:26:14 chatbry volumio[1135]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.19 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 oct. 11 12:26:14 chatbry kernel: usb 1-1.1-port2: over-current change #42 oct. 11 12:26:14 chatbry kernel: usb 1-1-port2: over-current change #36 oct. 11 12:26:14 chatbry volumio[1135]: verbose: New Socket.io Connection to 192.168.1.64 from 192.168.1.19 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 oct. 11 12:26:14 chatbry kernel: usb 1-1.1-port2: over-current change #43 oct. 11 12:26:14 chatbry volumio[1135]: info: Discovery: adding 9c0a4e48-fb30-4943-b9b0-01588af221b5 oct. 11 12:26:14 chatbry volumio[1135]: info: Discovery: Found device Chatbry oct. 11 12:26:14 chatbry kernel: usb 1-1-port3: over-current change #34 oct. 11 12:26:14 chatbry volumio[1135]: info: CoreCommandRouter::volumioGetState oct. 11 12:26:14 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:14 chatbry kernel: usb 1-1.1-port3: over-current change #39 oct. 11 12:26:14 chatbry kernel: usb 1-1-port3: over-current condition oct. 11 12:26:14 chatbry kernel: usb 1-1-port4: over-current change #33 oct. 11 12:26:14 chatbry volumio[1135]: info: VolumeController:: Volume=60 Mute =false oct. 11 12:26:14 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:14 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:14 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:14 chatbry kernel: usb 1-1-port2: over-current change #37 oct. 11 12:26:14 chatbry kernel: usb 1-1.1-port2: over-current change #44 oct. 11 12:26:14 chatbry volumio[1135]: info: CoreCommandRouter::volumioGetState oct. 11 12:26:14 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:15 chatbry sudo[1315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service oct. 11 12:26:15 chatbry sudo[1315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:15 chatbry kernel: usb 1-1.1-port3: over-current change #40 oct. 11 12:26:15 chatbry kernel: usb 1-1-port3: over-current change #35 oct. 11 12:26:15 chatbry kernel: usb 1-1-port4: over-current change #34 oct. 11 12:26:15 chatbry kernel: usb 1-1.1-port2: over-current change #45 oct. 11 12:26:15 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom oct. 11 12:26:15 chatbry volumio[1135]: info: CoreCommandRouter::volumioGetVisibleSources oct. 11 12:26:15 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources oct. 11 12:26:15 chatbry kernel: usb 1-1-port2: over-current change #38 oct. 11 12:26:15 chatbry kernel: usb 1-1.1-port3: over-current change #41 oct. 11 12:26:15 chatbry volumio[1135]: info: Listing playlists oct. 11 12:26:15 chatbry kernel: usb 1-1.1-port2: over-current change #46 oct. 11 12:26:15 chatbry kernel: usb 1-1-port3: over-current change #36 oct. 11 12:26:15 chatbry volumio[1135]: info: Discovery: this is already registered, 9c0a4e48-fb30-4943-b9b0-01588af221b5 oct. 11 12:26:15 chatbry volumio[1135]: info: Discovery: Found device Chatbry oct. 11 12:26:15 chatbry volumio[1135]: info: CoreCommandRouter::volumioGetState oct. 11 12:26:15 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:16 chatbry volumio[1135]: info: Completed loading Core Plugins oct. 11 12:26:16 chatbry kernel: usb 1-1.1-port3: over-current change #42 oct. 11 12:26:16 chatbry kernel: usb 1-1-port4: over-current change #35 oct. 11 12:26:16 chatbry volumio[1135]: info: Preparing to generate the ALSA configuration file oct. 11 12:26:16 chatbry kernel: usb 1-1.1-port2: over-current change #47 oct. 11 12:26:16 chatbry kernel: usb 1-1-port2: over-current change #39 oct. 11 12:26:16 chatbry kernel: usb 1-1.1-port3: over-current change #43 oct. 11 12:26:16 chatbry kernel: usb 1-1-port3: over-current change #37 oct. 11 12:26:16 chatbry volumio[1135]: info: Asound.conf file unchanged, so no further update is needed oct. 11 12:26:16 chatbry volumio[1135]: info: Output device has changed, restarting MPD oct. 11 12:26:16 chatbry kernel: usb 1-1-port4: over-current change #36 oct. 11 12:26:16 chatbry kernel: usb 1-1.1-port2: over-current change #48 oct. 11 12:26:16 chatbry volumio[1135]: info: Output device has changed, restarting Shairport Sync oct. 11 12:26:16 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:16 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:26:16 chatbry volumio[1135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 oct. 11 12:26:16 chatbry volumio[1135]: info: ___________ START PLUGINS ___________ oct. 11 12:26:16 chatbry sudo[1329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf oct. 11 12:26:16 chatbry sudo[1329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:16 chatbry sudo[1334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service oct. 11 12:26:16 chatbry kernel: usb 1-1-port2: over-current change #40 oct. 11 12:26:16 chatbry kernel: usb 1-1.1-port3: over-current change #44 oct. 11 12:26:16 chatbry sudo[1334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:16 chatbry sudo[1329]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:16 chatbry volumio[1135]: info: ControllerMpd::onStart: Initializing MPD oct. 11 12:26:16 chatbry volumio[1135]: info: Creating MPD Configuration file oct. 11 12:26:17 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam oct. 11 12:26:17 chatbry volumio[1135]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] oct. 11 12:26:17 chatbry volumio[1135]: info: [1791714377106] CoreMusicLibrary::Adding element Serveurs Média oct. 11 12:26:17 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources oct. 11 12:26:17 chatbry kernel: usb 1-1-port3: over-current change #38 oct. 11 12:26:17 chatbry sudo[1343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf oct. 11 12:26:17 chatbry sudo[1343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:17 chatbry kernel: usb 1-1.1-port2: over-current change #49 oct. 11 12:26:17 chatbry volumio[1135]: info: UPNP Browser: Client initialized successfully oct. 11 12:26:17 chatbry systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. oct. 11 12:26:17 chatbry systemd[1]: Starting mpd.service - Music Player Daemon... oct. 11 12:26:17 chatbry sudo[1343]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:17 chatbry sudo[1345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service oct. 11 12:26:17 chatbry sudo[1345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:17 chatbry kernel: usb 1-1-port4: over-current change #37 oct. 11 12:26:17 chatbry kernel: usb 1-1.1-port3: over-current change #45 oct. 11 12:26:17 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:17 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:26:17 chatbry sudo[1348]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log oct. 11 12:26:17 chatbry sudo[1348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) oct. 11 12:26:17 chatbry sudo[1354]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory oct. 11 12:26:17 chatbry sudo[1348]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:17 chatbry kernel: usb 1-1-port2: over-current change #41 oct. 11 12:26:17 chatbry systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... oct. 11 12:26:17 chatbry volumio[1135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 oct. 11 12:26:17 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:17 chatbry systemd[1]: mpd.service: Deactivated successfully. oct. 11 12:26:17 chatbry systemd[1]: Stopped mpd.service - Music Player Daemon. oct. 11 12:26:17 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:26:17 chatbry systemd[1]: mpd.socket: Deactivated successfully. oct. 11 12:26:17 chatbry systemd[1]: Closed mpd.socket - Music Player Daemon Socket. oct. 11 12:26:17 chatbry systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... oct. 11 12:26:17 chatbry systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. oct. 11 12:26:17 chatbry systemd[1]: Starting mpd.service - Music Player Daemon... oct. 11 12:26:17 chatbry volumio[1135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 oct. 11 12:26:17 chatbry volumio[1135]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] oct. 11 12:26:17 chatbry volumio[1135]: info: [1791714377857] CoreMusicLibrary::Adding element Last_100 oct. 11 12:26:17 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources oct. 11 12:26:17 chatbry volumio[1135]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] oct. 11 12:26:17 chatbry volumio[1135]: info: [1791714377862] CoreMusicLibrary::Adding element Webradio oct. 11 12:26:17 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 11 using dwc_otg oct. 11 12:26:17 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources oct. 11 12:26:18 chatbry sudo[1366]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log oct. 11 12:26:18 chatbry sudo[1366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) oct. 11 12:26:18 chatbry sudo[1376]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory oct. 11 12:26:18 chatbry sudo[1366]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:18 chatbry kernel: usb 1-1.1.2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 oct. 11 12:26:18 chatbry kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 oct. 11 12:26:18 chatbry kernel: usb 1-1.1.2: Product: SanDisk 3.2Gen1 oct. 11 12:26:18 chatbry kernel: usb 1-1.1.2: Manufacturer: USB oct. 11 12:26:18 chatbry kernel: usb 1-1.1.2: SerialNumber: 01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476 oct. 11 12:26:18 chatbry kernel: usb-storage 1-1.1.2:1.0: USB Mass Storage device detected oct. 11 12:26:18 chatbry kernel: scsi host0: usb-storage 1-1.1.2:1.0 oct. 11 12:26:18 chatbry volumio[1261]: Starting albumart workers oct. 11 12:26:18 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion oct. 11 12:26:18 chatbry volumio[1260]: Starting albumart workers oct. 11 12:26:18 chatbry volumio[1135]: info: Initializing BBC Radios oct. 11 12:26:18 chatbry volumio[1259]: Starting albumart workers oct. 11 12:26:19 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion oct. 11 12:26:19 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:26:19 chatbry volumio[1135]: info: AutoStart - onStart - waiting for system ready state oct. 11 12:26:19 chatbry volumio[1135]: info: AutoStart - Polling config: interval=10000ms, maxAttempts=20 oct. 11 12:26:19 chatbry volumio[1135]: info: AutoStart - Maximum wait time: 200 seconds oct. 11 12:26:19 chatbry volumio[1135]: info: AutoStart - Startup volume enabled, level=60 oct. 11 12:26:19 chatbry volumio[1135]: info: AutoStart - Check #1/20 - VOLUMIO_SYSTEM_STATUS = starting oct. 11 12:26:19 chatbry kernel: scsi 0:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 oct. 11 12:26:19 chatbry kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 oct. 11 12:26:19 chatbry kernel: sd 0:0:0:0: [sda] 30081024 512-byte logical blocks: (15.4 GB/14.3 GiB) oct. 11 12:26:19 chatbry kernel: sd 0:0:0:0: [sda] Write Protect is off oct. 11 12:26:19 chatbry kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 oct. 11 12:26:19 chatbry kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA oct. 11 12:26:19 chatbry kernel: sda: sda1 oct. 11 12:26:19 chatbry kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk oct. 11 12:26:19 chatbry volumio[1135]: info: [now-playing] ConfigUpdater: config is up to date. oct. 11 12:26:19 chatbry volumio[1135]: info: Cannot retrieve data for calling home oct. 11 12:26:21 chatbry volumio-remote-updater[637]: [2026-10-11 12:26:21] [connect] Successful connection oct. 11 12:26:24 chatbry systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. oct. 11 12:26:25 chatbry systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. oct. 11 12:26:25 chatbry systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. oct. 11 12:26:25 chatbry sudo[1315]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:25 chatbry volumio[1135]: info: [now-playing] App is listening on port 4004. oct. 11 12:26:25 chatbry volumio[1135]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds oct. 11 12:26:26 chatbry volumio[1135]: info: MPD Permissions set oct. 11 12:26:26 chatbry volumio[1135]: info: MPD Permissions set oct. 11 12:26:26 chatbry volumio[1135]: info: Upmpdcli Daemon Started oct. 11 12:26:26 chatbry volumio[1135]: info: Mounting Device 61BB-782A oct. 11 12:26:26 chatbry sudo[1434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/61BB-782A -o noatime,dmask=0000,fmask=0000,iocharset=utf8 oct. 11 12:26:26 chatbry sudo[1434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:26 chatbry mpd[1377]: 2026-10-11T12:26:26 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg oct. 11 12:26:26 chatbry systemd[1]: Started mpd.service - Music Player Daemon. oct. 11 12:26:26 chatbry sudo[1345]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:26 chatbry sudo[1334]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:26 chatbry kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! oct. 11 12:26:26 chatbry kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. oct. 11 12:26:26 chatbry sudo[1434]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:27 chatbry kernel: usb 1-1-port2: over-current change #42 oct. 11 12:26:27 chatbry kernel: usb 1-1.1-port2: over-current change #50 oct. 11 12:26:27 chatbry kernel: usb 1-1-port3: over-current change #39 oct. 11 12:26:27 chatbry kernel: usb 1-1.1-port3: over-current change #46 oct. 11 12:26:27 chatbry kernel: usb 1-1-port4: over-current change #38 oct. 11 12:26:27 chatbry kernel: usb 1-1.1-port2: over-current change #51 oct. 11 12:26:27 chatbry kernel: usb 1-1-port2: over-current change #43 oct. 11 12:26:27 chatbry kernel: usb 1-1.1-port3: over-current change #47 oct. 11 12:26:27 chatbry volumio[1135]: verbose: New Socket.io Connection to chatbry.local from 192.168.1.19 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 oct. 11 12:26:28 chatbry kernel: usb 1-1-port3: over-current change #40 oct. 11 12:26:28 chatbry volumio[1135]: info: Completed starting Core Plugins oct. 11 12:26:28 chatbry volumio[1135]: info: ------------------------------------------- oct. 11 12:26:28 chatbry volumio[1135]: info: ----- MyVolumio plugins startup ---- oct. 11 12:26:28 chatbry volumio[1135]: info: ------------------------------------------- oct. 11 12:26:28 chatbry volumio[1135]: info: [MyVolumio PluginManager] Fetching plans data.... oct. 11 12:26:28 chatbry kernel: usb 1-1.1-port2: over-current change #52 oct. 11 12:26:28 chatbry kernel: usb 1-1-port4: over-current change #39 oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:26:28 chatbry kernel: usb 1-1.1-port3: over-current change #48 oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry kernel: usb 1-1-port2: over-current change #44 oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:26:28 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:26:28 chatbry kernel: usb 1-1.1-port2: over-current change #53 oct. 11 12:26:28 chatbry kernel: usb 1-1-port3: over-current change #41 oct. 11 12:26:28 chatbry kernel: usb 1-1.1-port2: over-current change #54 oct. 11 12:26:28 chatbry kernel: usb 1-1-port2: over-current change #45 oct. 11 12:26:29 chatbry kernel: usb 1-1.1-port3: over-current change #49 oct. 11 12:26:29 chatbry kernel: usb 1-1-port3: over-current change #42 oct. 11 12:26:29 chatbry volumio[1135]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 oct. 11 12:26:29 chatbry volumio[1135]: info: MPD running with PID1377 oct. 11 12:26:29 chatbry volumio[1135]: ,establishing connection oct. 11 12:26:29 chatbry volumio[1135]: info: AutoStart - Check #2/20 - VOLUMIO_SYSTEM_STATUS = starting oct. 11 12:26:29 chatbry kernel: usb 1-1-port4: over-current change #40 oct. 11 12:26:29 chatbry kernel: usb 1-1-port2: over-current change #46 oct. 11 12:26:29 chatbry volumio[1135]: info: CoreCommandRouter::volumioGetState oct. 11 12:26:29 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:29 chatbry kernel: usb 1-1.1-port2: over-current change #55 oct. 11 12:26:29 chatbry kernel: usb 1-1-port3: over-current change #43 oct. 11 12:26:29 chatbry kernel: usb 1-1.1.2: USB disconnect, device number 11 oct. 11 12:26:29 chatbry volumio[1135]: error: MPD error: The expression evaluated to a falsy value: oct. 11 12:26:29 chatbry volumio[1135]: assert.ok(self.idling) oct. 11 12:26:29 chatbry volumio[1135]: error: The expression evaluated to a falsy value: oct. 11 12:26:29 chatbry volumio[1135]: assert.ok(self.idling) oct. 11 12:26:29 chatbry volumio[1135]: error: MPD error: The expression evaluated to a falsy value: oct. 11 12:26:29 chatbry volumio[1135]: assert.ok(self.idling) oct. 11 12:26:29 chatbry volumio[1135]: error: The expression evaluated to a falsy value: oct. 11 12:26:29 chatbry volumio[1135]: assert.ok(self.idling) oct. 11 12:26:29 chatbry kernel: sda: detected capacity change from 30081024 to 0 oct. 11 12:26:30 chatbry volumio[1135]: info: Scanning new location : "USB/61BB-782A" oct. 11 12:26:30 chatbry volumio[1135]: info: CoreCommandRouter::volumioGetQueue oct. 11 12:26:30 chatbry volumio[1135]: info: CoreStateMachine::getQueue oct. 11 12:26:30 chatbry volumio[1135]: info: CorePlayQueue::getQueue oct. 11 12:26:30 chatbry kernel: FAT-fs (sda1): Directory bread(block 14720) failed oct. 11 12:26:30 chatbry kernel: FAT-fs (sda1): Directory bread(block 14721) failed oct. 11 12:26:30 chatbry kernel: FAT-fs (sda1): Directory bread(block 14722) failed oct. 11 12:26:30 chatbry kernel: FAT-fs (sda1): Directory bread(block 14723) failed oct. 11 12:26:30 chatbry kernel: FAT-fs (sda1): Directory bread(block 14724) failed oct. 11 12:26:30 chatbry kernel: FAT-fs (sda1): Directory bread(block 14725) failed oct. 11 12:26:30 chatbry kernel: FAT-fs (sda1): Directory bread(block 14726) failed oct. 11 12:26:30 chatbry kernel: FAT-fs (sda1): Directory bread(block 14727) failed oct. 11 12:26:30 chatbry kernel: FAT-fs (sda1): Directory bread(block 14728) failed oct. 11 12:26:30 chatbry kernel: FAT-fs (sda1): Directory bread(block 14729) failed oct. 11 12:26:30 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid oct. 11 12:26:30 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel oct. 11 12:26:30 chatbry volumio[1135]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] oct. 11 12:26:30 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction oct. 11 12:26:30 chatbry volumio[1135]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs oct. 11 12:26:30 chatbry volumio[1135]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] oct. 11 12:26:30 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection oct. 11 12:26:30 chatbry volumio[1135]: info: Starting Shairport Sync oct. 11 12:26:30 chatbry volumio[1135]: info: Starting Shairport Sync oct. 11 12:26:30 chatbry kernel: usb 1-1-port4: over-current change #41 oct. 11 12:26:30 chatbry volumio[1135]: info: Starting Shairport Sync oct. 11 12:26:30 chatbry sudo[1483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync oct. 11 12:26:30 chatbry sudo[1483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:30 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:30 chatbry volumio[1135]: info: oct. 11 12:26:30 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:30 chatbry volumio[1135]: error: updateQueue error: null oct. 11 12:26:30 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:30 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:30 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:30 chatbry volumio[1135]: info: oct. 11 12:26:30 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:30 chatbry sudo[1484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync oct. 11 12:26:30 chatbry sudo[1484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:30 chatbry kernel: usb 1-1.1-port3: over-current change #50 oct. 11 12:26:30 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:30 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:30 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:30 chatbry volumio[1135]: info: oct. 11 12:26:30 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:30 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:30 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:30 chatbry volumio[1135]: info: sendMpdCommand status took 37 milliseconds oct. 11 12:26:30 chatbry volumio[1135]: info: sendMpdCommand status took 114 milliseconds oct. 11 12:26:30 chatbry volumio[1135]: info: sendMpdCommand status took 113 milliseconds oct. 11 12:26:30 chatbry volumio[1135]: info: sendMpdCommand status took 91 milliseconds oct. 11 12:26:30 chatbry volumio[1135]: info: sendMpdCommand status took 90 milliseconds oct. 11 12:26:30 chatbry volumio[1135]: info: sendMpdCommand status took 89 milliseconds oct. 11 12:26:30 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:30 chatbry systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... oct. 11 12:26:30 chatbry systemd[1]: shairport-sync.service: Deactivated successfully. oct. 11 12:26:30 chatbry systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. oct. 11 12:26:30 chatbry systemd[1]: shairport-sync.service: Consumed 1.994s CPU time. oct. 11 12:26:30 chatbry kernel: usb 1-1-port2: over-current change #47 oct. 11 12:26:30 chatbry sudo[1487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync oct. 11 12:26:30 chatbry sudo[1487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:30 chatbry systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. oct. 11 12:26:30 chatbry systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... oct. 11 12:26:30 chatbry systemd[1]: shairport-sync.service: Deactivated successfully. oct. 11 12:26:30 chatbry systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. oct. 11 12:26:30 chatbry kernel: usb 1-1.1-port2: over-current change #56 oct. 11 12:26:30 chatbry systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. oct. 11 12:26:30 chatbry sudo[1483]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:30 chatbry sudo[1484]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:30 chatbry kernel: usb 1-1-port3: over-current change #44 oct. 11 12:26:30 chatbry systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... oct. 11 12:26:30 chatbry systemd[1]: shairport-sync.service: Deactivated successfully. oct. 11 12:26:30 chatbry systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. oct. 11 12:26:30 chatbry systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. oct. 11 12:26:30 chatbry sudo[1487]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:30 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:30 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:30 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:30 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:30 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:30 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:30 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:30 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:30 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:30 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:30 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:31 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:31 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:31 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:31 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:31 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:31 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:31 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 12 using dwc_otg oct. 11 12:26:31 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:31 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:31 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:31 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:31 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:31 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:31 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:31 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:31 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:31 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:31 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:31 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:31 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:31 chatbry kernel: usb 1-1.1.2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 oct. 11 12:26:31 chatbry kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 oct. 11 12:26:31 chatbry kernel: usb 1-1.1.2: Product: SanDisk 3.2Gen1 oct. 11 12:26:31 chatbry kernel: usb 1-1.1.2: Manufacturer: USB oct. 11 12:26:31 chatbry kernel: usb 1-1.1.2: SerialNumber: 01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476 oct. 11 12:26:31 chatbry kernel: usb-storage 1-1.1.2:1.0: USB Mass Storage device detected oct. 11 12:26:31 chatbry kernel: scsi host1: usb-storage 1-1.1.2:1.0 oct. 11 12:26:31 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:31 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:31 chatbry volumio[1135]: info: ------------------------------ 1126ms oct. 11 12:26:31 chatbry volumio[1135]: info: ------------------------------ 1121ms oct. 11 12:26:31 chatbry volumio[1135]: info: ------------------------------ 1118ms oct. 11 12:26:31 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:31 chatbry volumio[1135]: info: oct. 11 12:26:31 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:31 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:31 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:31 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:31 chatbry volumio[1135]: info: oct. 11 12:26:31 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:31 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:31 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:31 chatbry sudo[1539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service oct. 11 12:26:31 chatbry sudo[1539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:31 chatbry volumio[1135]: info: sendMpdCommand status took 315 milliseconds oct. 11 12:26:31 chatbry volumio[1135]: info: sendMpdCommand status took 314 milliseconds oct. 11 12:26:31 chatbry volumio[1135]: info: sendMpdCommand status took 314 milliseconds oct. 11 12:26:31 chatbry volumio[1135]: info: sendMpdCommand status took 350 milliseconds oct. 11 12:26:31 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:31 chatbry sudo[1541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service oct. 11 12:26:31 chatbry sudo[1541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:31 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:31 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:31 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:31 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:31 chatbry systemd[1]: setdatetime-helper.service: Deactivated successfully. oct. 11 12:26:31 chatbry systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. oct. 11 12:26:31 chatbry systemd[1]: setdatetime-helper.service: Consumed 2.354s CPU time. oct. 11 12:26:31 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:31 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:31 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:31 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:31 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:31 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:31 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:31 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:31 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:31 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:31 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:31 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:31 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:31 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:31 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:31 chatbry systemd[1]: Started mpd_monitor.service - MPD Monitor Service. oct. 11 12:26:31 chatbry mpd_monitor.sh[1554]: MPD Monitor Service: Starting MPD Monitor Service oct. 11 12:26:31 chatbry sudo[1541]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:31 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:31 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:31 chatbry sudo[1539]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:31 chatbry volumio[1135]: info: ------------------------------ 675ms oct. 11 12:26:31 chatbry volumio[1135]: info: ------------------------------ 673ms oct. 11 12:26:32 chatbry volumio[1135]: info: Shairport-Sync Started oct. 11 12:26:32 chatbry volumio[1135]: Error adding Membership: Error: addMembership EINVAL oct. 11 12:26:32 chatbry volumio[1135]: info: Shairport-Sync Started oct. 11 12:26:32 chatbry volumio[1135]: info: Shairport-Sync Started oct. 11 12:26:32 chatbry volumio[1135]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-path/platform-3f980000.usb-usb-0:1.1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/61BB-782A /dev/disk/by-id/usb-USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"32","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"61BB-782A","ID_FS_UUID_ENC":"61BB-782A","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"SanDisk_3.2Gen1","ID_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_MODEL_ID":"5583","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"32","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"30080992","ID_PART_ENTRY_TYPE":"0xc","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-3f980000.usb-usb-0:1.1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0","ID_SERIAL_SHORT":"01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"SanDisk_3.2Gen1","ID_USB_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_USB_MODEL_ID":"5583","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0","ID_USB_SERIAL_SHORT":"01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476","ID_USB_TYPE":"disk","ID_USB_VENDOR":"USB","ID_USB_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_USB_VENDOR_ID":"0781","ID_VENDOR":"USB","ID_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2480","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"62247645"} oct. 11 12:26:32 chatbry sudo[1560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 oct. 11 12:26:32 chatbry sudo[1560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:32 chatbry systemd[1]: media-61BB\x2d782A.mount: Deactivated successfully. oct. 11 12:26:32 chatbry kernel: scsi 1:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 oct. 11 12:26:32 chatbry kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 oct. 11 12:26:32 chatbry kernel: sd 1:0:0:0: [sda] 30081024 512-byte logical blocks: (15.4 GB/14.3 GiB) oct. 11 12:26:32 chatbry kernel: sd 1:0:0:0: [sda] Write Protect is off oct. 11 12:26:32 chatbry kernel: sd 1:0:0:0: [sda] Mode Sense: 43 00 00 00 oct. 11 12:26:32 chatbry kernel: sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA oct. 11 12:26:32 chatbry kernel: sda: sda1 oct. 11 12:26:32 chatbry kernel: sd 1:0:0:0: [sda] Attached SCSI removable disk oct. 11 12:26:32 chatbry sudo[1560]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:32 chatbry volumio[1135]: info: Successfully started MPD Monitor oct. 11 12:26:32 chatbry volumio[1135]: info: Successfully started MPD Monitor oct. 11 12:26:33 chatbry volumio[1135]: info: Mounting Device 61BB-782A oct. 11 12:26:33 chatbry sudo[1569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/61BB-782A -o noatime,dmask=0000,fmask=0000,iocharset=utf8 oct. 11 12:26:33 chatbry sudo[1569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:33 chatbry kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! oct. 11 12:26:33 chatbry kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. oct. 11 12:26:33 chatbry sudo[1569]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:33 chatbry volumio[1135]: info: CoreCommandRouter::volumioGetState oct. 11 12:26:33 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:33 chatbry kernel: usb 1-1-port2: over-current change #48 oct. 11 12:26:33 chatbry kernel: usb 1-1.1-port2: over-current change #57 oct. 11 12:26:33 chatbry kernel: usb 1-1-port3: over-current change #45 oct. 11 12:26:34 chatbry kernel: usb 1-1.1-port3: over-current change #51 oct. 11 12:26:34 chatbry kernel: usb 1-1-port4: over-current change #42 oct. 11 12:26:34 chatbry kernel: usb 1-1.1-port2: over-current change #58 oct. 11 12:26:34 chatbry kernel: usb 1-1-port2: over-current change #49 oct. 11 12:26:34 chatbry kernel: usb 1-1.1-port3: over-current change #52 oct. 11 12:26:34 chatbry kernel: usb 1-1-port3: over-current change #46 oct. 11 12:26:34 chatbry kernel: usb 1-1.1-port2: over-current change #59 oct. 11 12:26:34 chatbry kernel: usb 1-1-port4: over-current change #43 oct. 11 12:26:35 chatbry kernel: usb 1-1.1-port3: over-current change #53 oct. 11 12:26:35 chatbry kernel: usb 1-1-port2: over-current change #50 oct. 11 12:26:35 chatbry kernel: usb 1-1.1-port2: over-current change #60 oct. 11 12:26:35 chatbry kernel: usb 1-1-port3: over-current change #47 oct. 11 12:26:35 chatbry kernel: usb 1-1.1-port3: over-current change #54 oct. 11 12:26:35 chatbry kernel: usb 1-1-port4: over-current change #44 oct. 11 12:26:35 chatbry kernel: usb 1-1-port2: over-current change #51 oct. 11 12:26:36 chatbry volumio-remote-updater[637]: [2026-10-11 12:26:36] [connect] Successful connection oct. 11 12:26:36 chatbry kernel: usb 1-1.1.2: reset high-speed USB device number 12 using dwc_otg oct. 11 12:26:36 chatbry volumio-remote-updater[637]: [2026-10-11 12:26:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1791714396 101 oct. 11 12:26:36 chatbry volumio[1135]: 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 oct. 11 12:26:36 chatbry volumio[1135]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/61BB-782A oct. 11 12:26:36 chatbry kernel: usb 1-1-port2: over-current change #52 oct. 11 12:26:36 chatbry kernel: usb 1-1.1-port2: over-current change #61 oct. 11 12:26:36 chatbry kernel: usb 1-1-port3: over-current change #48 oct. 11 12:26:36 chatbry kernel: usb 1-1.1-port3: over-current change #55 oct. 11 12:26:36 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium oct. 11 12:26:36 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium oct. 11 12:26:36 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium oct. 11 12:26:36 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium oct. 11 12:26:36 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium oct. 11 12:26:36 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium oct. 11 12:26:36 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium oct. 11 12:26:36 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium oct. 11 12:26:36 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium oct. 11 12:26:37 chatbry kernel: usb 1-1-port4: over-current change #45 oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso oct. 11 12:26:37 chatbry volumio[1135]: info: Adding plugin bluetooth to MyMusic Plugins oct. 11 12:26:37 chatbry volumio[1135]: info: Adding plugin multiroom to MyMusic Plugins oct. 11 12:26:37 chatbry volumio[1135]: info: Adding plugin metavolumio to MyMusic Plugins oct. 11 12:26:37 chatbry volumio[1135]: info: Adding plugin cd_controller to MyMusic Plugins oct. 11 12:26:37 chatbry volumio[1135]: info: Adding plugin qobuzconnect to MyMusic Plugins oct. 11 12:26:37 chatbry volumio[1135]: info: Adding plugin smart_inputs to MyMusic Plugins oct. 11 12:26:37 chatbry volumio[1135]: info: Adding plugin tidalconnect to MyMusic Plugins oct. 11 12:26:37 chatbry volumio[1135]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... oct. 11 12:26:37 chatbry kernel: usb 1-1.1.2: USB disconnect, device number 12 oct. 11 12:26:37 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 13 using dwc_otg oct. 11 12:26:37 chatbry kernel: usb 1-1.1.2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 oct. 11 12:26:37 chatbry kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 oct. 11 12:26:37 chatbry kernel: usb 1-1.1.2: Product: SanDisk 3.2Gen1 oct. 11 12:26:37 chatbry kernel: usb 1-1.1.2: Manufacturer: USB oct. 11 12:26:37 chatbry kernel: usb 1-1.1.2: SerialNumber: 01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476 oct. 11 12:26:37 chatbry kernel: usb-storage 1-1.1.2:1.0: USB Mass Storage device detected oct. 11 12:26:37 chatbry kernel: scsi host0: usb-storage 1-1.1.2:1.0 oct. 11 12:26:38 chatbry kernel: scsi 0:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 oct. 11 12:26:38 chatbry kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 oct. 11 12:26:38 chatbry kernel: sd 0:0:0:0: [sdb] 30081024 512-byte logical blocks: (15.4 GB/14.3 GiB) oct. 11 12:26:38 chatbry kernel: sd 0:0:0:0: [sdb] Write Protect is off oct. 11 12:26:38 chatbry kernel: sd 0:0:0:0: [sdb] Mode Sense: 43 00 00 00 oct. 11 12:26:38 chatbry kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA oct. 11 12:26:38 chatbry kernel: sdb: sdb1 oct. 11 12:26:38 chatbry kernel: sd 0:0:0:0: [sdb] Attached SCSI removable disk oct. 11 12:26:40 chatbry kernel: usb 1-1.1-port2: over-current change #62 oct. 11 12:26:40 chatbry kernel: usb 1-1-port2: over-current change #53 oct. 11 12:26:40 chatbry kernel: usb 1-1.1-port3: over-current change #56 oct. 11 12:26:40 chatbry kernel: usb 1-1-port3: over-current change #49 oct. 11 12:26:40 chatbry kernel: usb 1-1-port4: over-current change #46 oct. 11 12:26:40 chatbry kernel: usb 1-1.1.2: USB disconnect, device number 13 oct. 11 12:26:41 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 14 using dwc_otg oct. 11 12:26:41 chatbry kernel: usb 1-1.1.2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 oct. 11 12:26:41 chatbry kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 oct. 11 12:26:41 chatbry kernel: usb 1-1.1.2: Product: SanDisk 3.2Gen1 oct. 11 12:26:41 chatbry kernel: usb 1-1.1.2: Manufacturer: USB oct. 11 12:26:41 chatbry kernel: usb 1-1.1.2: SerialNumber: 01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476 oct. 11 12:26:41 chatbry kernel: usb-storage 1-1.1.2:1.0: USB Mass Storage device detected oct. 11 12:26:41 chatbry kernel: scsi host0: usb-storage 1-1.1.2:1.0 oct. 11 12:26:42 chatbry kernel: usb 1-1-port2: over-current change #54 oct. 11 12:26:42 chatbry kernel: usb 1-1.1-port2: over-current change #63 oct. 11 12:26:42 chatbry kernel: usb 1-1-port3: over-current change #50 oct. 11 12:26:42 chatbry kernel: usb 1-1.1-port3: over-current change #57 oct. 11 12:26:42 chatbry kernel: usb 1-1-port4: over-current change #47 oct. 11 12:26:43 chatbry kernel: usb 1-1.1.2: reset high-speed USB device number 14 using dwc_otg oct. 11 12:26:43 chatbry kernel: scsi 0:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 oct. 11 12:26:43 chatbry kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 oct. 11 12:26:43 chatbry kernel: sd 0:0:0:0: [sdb] 30081024 512-byte logical blocks: (15.4 GB/14.3 GiB) oct. 11 12:26:43 chatbry kernel: sd 0:0:0:0: [sdb] Write Protect is off oct. 11 12:26:43 chatbry kernel: sd 0:0:0:0: [sdb] Mode Sense: 43 00 00 00 oct. 11 12:26:43 chatbry kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA oct. 11 12:26:43 chatbry kernel: sdb: sdb1 oct. 11 12:26:43 chatbry kernel: sd 0:0:0:0: [sdb] Attached SCSI removable disk oct. 11 12:26:43 chatbry kernel: usb 1-1-port2: over-current change #55 oct. 11 12:26:43 chatbry kernel: usb 1-1-port3: over-current change #51 oct. 11 12:26:43 chatbry kernel: usb 1-1-port4: over-current change #48 oct. 11 12:26:44 chatbry kernel: sd 0:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s oct. 11 12:26:44 chatbry kernel: sd 0:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 00 00 38 00 00 08 00 oct. 11 12:26:44 chatbry kernel: I/O error, dev sdb, sector 56 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 oct. 11 12:26:44 chatbry kernel: usb 1-1.1-port2: over-current change #64 oct. 11 12:26:44 chatbry kernel: sd 0:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s oct. 11 12:26:44 chatbry kernel: sd 0:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 00 00 38 00 00 08 00 oct. 11 12:26:44 chatbry kernel: I/O error, dev sdb, sector 56 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 oct. 11 12:26:44 chatbry kernel: Buffer I/O error on dev sdb, logical block 7, async page read oct. 11 12:26:44 chatbry kernel: usb 1-1.1.2: USB disconnect, device number 14 oct. 11 12:26:44 chatbry kernel: usb 1-1.1-port3: over-current change #58 oct. 11 12:26:45 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 15 using dwc_otg oct. 11 12:26:45 chatbry kernel: usb 1-1.1.2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 oct. 11 12:26:45 chatbry kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 oct. 11 12:26:45 chatbry kernel: usb 1-1.1.2: Product: SanDisk 3.2Gen1 oct. 11 12:26:45 chatbry kernel: usb 1-1.1.2: Manufacturer: USB oct. 11 12:26:45 chatbry kernel: usb 1-1.1.2: SerialNumber: 01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476 oct. 11 12:26:45 chatbry kernel: usb-storage 1-1.1.2:1.0: USB Mass Storage device detected oct. 11 12:26:45 chatbry kernel: scsi host0: usb-storage 1-1.1.2:1.0 oct. 11 12:26:46 chatbry kernel: scsi 0:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 oct. 11 12:26:46 chatbry kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 oct. 11 12:26:46 chatbry kernel: sd 0:0:0:0: [sdb] 30081024 512-byte logical blocks: (15.4 GB/14.3 GiB) oct. 11 12:26:46 chatbry kernel: sd 0:0:0:0: [sdb] Write Protect is off oct. 11 12:26:46 chatbry kernel: sd 0:0:0:0: [sdb] Mode Sense: 43 00 00 00 oct. 11 12:26:46 chatbry kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA oct. 11 12:26:46 chatbry kernel: sdb: sdb1 oct. 11 12:26:46 chatbry kernel: sd 0:0:0:0: [sdb] Attached SCSI removable disk oct. 11 12:26:46 chatbry kernel: usb 1-1.1-port2: over-current change #65 oct. 11 12:26:46 chatbry kernel: usb 1-1-port2: over-current change #56 oct. 11 12:26:46 chatbry kernel: usb 1-1.1-port3: over-current change #59 oct. 11 12:26:47 chatbry kernel: usb 1-1-port3: over-current change #52 oct. 11 12:26:47 chatbry volumio[1135]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded oct. 11 12:26:47 chatbry volumio[1135]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio oct. 11 12:26:47 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:26:47 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:26:47 chatbry volumio[1135]: info: Starting MyVolumio Remote Streaming Endpoints oct. 11 12:26:47 chatbry volumio[1135]: info: MyVolumio login type: Token oct. 11 12:26:47 chatbry kernel: usb 1-1-port4: over-current change #49 oct. 11 12:26:47 chatbry volumio[1135]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started oct. 11 12:26:47 chatbry volumio[1135]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... oct. 11 12:26:47 chatbry kernel: usb 1-1.1.2: USB disconnect, device number 15 oct. 11 12:26:48 chatbry kernel: usb 1-1.1.2: new high-speed USB device number 16 using dwc_otg oct. 11 12:26:48 chatbry kernel: usb 1-1.1.2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 oct. 11 12:26:48 chatbry kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 oct. 11 12:26:48 chatbry kernel: usb 1-1.1.2: Product: SanDisk 3.2Gen1 oct. 11 12:26:48 chatbry kernel: usb 1-1.1.2: Manufacturer: USB oct. 11 12:26:48 chatbry kernel: usb 1-1.1.2: SerialNumber: 01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476 oct. 11 12:26:48 chatbry kernel: usb-storage 1-1.1.2:1.0: USB Mass Storage device detected oct. 11 12:26:48 chatbry kernel: scsi host0: usb-storage 1-1.1.2:1.0 oct. 11 12:26:49 chatbry kernel: scsi 0:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 oct. 11 12:26:49 chatbry kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 oct. 11 12:26:49 chatbry kernel: sd 0:0:0:0: [sdb] 30081024 512-byte logical blocks: (15.4 GB/14.3 GiB) oct. 11 12:26:49 chatbry kernel: sd 0:0:0:0: [sdb] Write Protect is off oct. 11 12:26:49 chatbry kernel: sd 0:0:0:0: [sdb] Mode Sense: 43 00 00 00 oct. 11 12:26:49 chatbry kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA oct. 11 12:26:49 chatbry kernel: sdb: sdb1 oct. 11 12:26:49 chatbry kernel: sd 0:0:0:0: [sdb] Attached SCSI removable disk oct. 11 12:26:51 chatbry upmpdcli[1623]: writing RSA key oct. 11 12:26:54 chatbry volumio[1135]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded oct. 11 12:26:54 chatbry volumio[1135]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services oct. 11 12:26:54 chatbry volumio[1135]: info: Streaming services startup oct. 11 12:26:54 chatbry volumio[1135]: info: Starting Streaming Daemon oct. 11 12:26:54 chatbry sudo[1634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service oct. 11 12:26:54 chatbry sudo[1634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:54 chatbry volumio[1135]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started oct. 11 12:26:54 chatbry sudo[1634]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:54 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion oct. 11 12:26:54 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:26:54 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled oct. 11 12:26:54 chatbry volumio[1135]: info: AutoStart - Check #3/20 - VOLUMIO_SYSTEM_STATUS = starting oct. 11 12:26:54 chatbry volumio[1135]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/host1/target1:0:0/1:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-path/platform-3f980000.usb-usb-0:1.1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/61BB-782A /dev/disk/by-id/usb-USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/host1/target1:0:0/1:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"33","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"61BB-782A","ID_FS_UUID_ENC":"61BB-782A","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"SanDisk_3.2Gen1","ID_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_MODEL_ID":"5583","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"32","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"30080992","ID_PART_ENTRY_TYPE":"0xc","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-3f980000.usb-usb-0:1.1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0","ID_SERIAL_SHORT":"01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"SanDisk_3.2Gen1","ID_USB_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_USB_MODEL_ID":"5583","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0","ID_USB_SERIAL_SHORT":"01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476","ID_USB_TYPE":"disk","ID_USB_VENDOR":"USB","ID_USB_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_USB_VENDOR_ID":"0781","ID_VENDOR":"USB","ID_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2542","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"75317741"} oct. 11 12:26:54 chatbry sudo[1645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 oct. 11 12:26:54 chatbry sudo[1645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:55 chatbry systemd[1]: media-61BB\x2d782A.mount: Deactivated successfully. oct. 11 12:26:55 chatbry sudo[1645]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:55 chatbry volumio[1135]: error: Cannot start Volumio Streaming Daemon oct. 11 12:26:55 chatbry volumio[1135]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service oct. 11 12:26:55 chatbry volumio[1135]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. oct. 11 12:26:55 chatbry volumio[1135]: info: Mounting Device 61BB-782A oct. 11 12:26:55 chatbry sudo[1654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/61BB-782A -o noatime,dmask=0000,fmask=0000,iocharset=utf8 oct. 11 12:26:55 chatbry sudo[1654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:56 chatbry kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! oct. 11 12:26:56 chatbry kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. oct. 11 12:26:56 chatbry sudo[1654]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:56 chatbry volumio[1135]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/host0/target0:0:0/0:0:0:0/block/sdb/sdb1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-path/platform-3f980000.usb-usb-0:1.1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/61BB-782A /dev/disk/by-id/usb-USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/host0/target0:0:0/0:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"34","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"61BB-782A","ID_FS_UUID_ENC":"61BB-782A","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"SanDisk_3.2Gen1","ID_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_MODEL_ID":"5583","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"32","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"30080992","ID_PART_ENTRY_TYPE":"0xc","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-3f980000.usb-usb-0:1.1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0","ID_SERIAL_SHORT":"01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"SanDisk_3.2Gen1","ID_USB_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_USB_MODEL_ID":"5583","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0","ID_USB_SERIAL_SHORT":"01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476","ID_USB_TYPE":"disk","ID_USB_VENDOR":"USB","ID_USB_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_USB_VENDOR_ID":"0781","ID_VENDOR":"USB","ID_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2580","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"81873712"} oct. 11 12:26:56 chatbry sudo[1659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 oct. 11 12:26:56 chatbry sudo[1659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:26:56 chatbry systemd[1]: media-61BB\x2d782A.mount: Deactivated successfully. oct. 11 12:26:56 chatbry sudo[1659]: pam_unix(sudo:session): session closed for user root oct. 11 12:26:56 chatbry volumio[1135]: info: Scanning removed location : "USB/61BB-782A" oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: info: oct. 11 12:26:57 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:57 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: info: oct. 11 12:26:57 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:57 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: info: oct. 11 12:26:57 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: info: oct. 11 12:26:57 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:57 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: info: sendMpdCommand status took 51 milliseconds oct. 11 12:26:57 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: info: sendMpdCommand status took 42 milliseconds oct. 11 12:26:57 chatbry volumio[1135]: info: sendMpdCommand status took 32 milliseconds oct. 11 12:26:57 chatbry volumio[1135]: info: sendMpdCommand status took 27 milliseconds oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:57 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:57 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:57 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:57 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:57 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:57 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:57 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:57 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:57 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:57 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:57 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:57 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:57 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:57 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:57 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:57 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:57 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:57 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:57 chatbry volumio[1135]: info: ------------------------------ 456ms oct. 11 12:26:57 chatbry volumio[1135]: info: ------------------------------ 437ms oct. 11 12:26:57 chatbry volumio[1135]: info: Scanning new location : "USB/61BB-782A" oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: info: oct. 11 12:26:57 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:57 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: info: oct. 11 12:26:57 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:57 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: info: oct. 11 12:26:57 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: info: oct. 11 12:26:57 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:57 chatbry volumio[1135]: info: sendMpdCommand status took 557 milliseconds oct. 11 12:26:57 chatbry volumio[1135]: info: sendMpdCommand status took 548 milliseconds oct. 11 12:26:57 chatbry volumio[1135]: info: sendMpdCommand status took 538 milliseconds oct. 11 12:26:57 chatbry volumio[1135]: info: sendMpdCommand status took 538 milliseconds oct. 11 12:26:57 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: info: sendMpdCommand status took 61 milliseconds oct. 11 12:26:57 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:57 chatbry volumio[1135]: info: sendMpdCommand status took 61 milliseconds oct. 11 12:26:57 chatbry volumio[1135]: info: sendMpdCommand status took 61 milliseconds oct. 11 12:26:57 chatbry volumio[1135]: info: sendMpdCommand status took 59 milliseconds oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:57 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:57 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:57 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:57 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:57 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:58 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:58 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:58 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:58 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:58 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:58 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:58 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:58 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:58 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:58 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:58 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:58 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:58 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:58 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:58 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:58 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:58 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:58 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:58 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:58 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:58 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:58 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:58 chatbry volumio[1135]: info: ------------------------------ 1225ms oct. 11 12:26:58 chatbry volumio[1135]: info: ------------------------------ 1216ms oct. 11 12:26:58 chatbry volumio[1135]: info: ------------------------------ 675ms oct. 11 12:26:58 chatbry volumio[1135]: info: ------------------------------ 674ms oct. 11 12:26:58 chatbry volumio[1135]: error: Cannot associate FS Label, not mounting oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:58 chatbry volumio[1135]: info: oct. 11 12:26:58 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:58 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:58 chatbry volumio[1135]: info: oct. 11 12:26:58 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:26:58 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:26:58 chatbry volumio[1135]: info: sendMpdCommand status took 886 milliseconds oct. 11 12:26:58 chatbry volumio[1135]: info: sendMpdCommand status took 886 milliseconds oct. 11 12:26:58 chatbry volumio[1135]: info: sendMpdCommand status took 831 milliseconds oct. 11 12:26:58 chatbry volumio[1135]: info: sendMpdCommand status took 831 milliseconds oct. 11 12:26:58 chatbry volumio[1135]: info: sendMpdCommand status took 36 milliseconds oct. 11 12:26:58 chatbry volumio[1135]: info: sendMpdCommand status took 5 milliseconds oct. 11 12:26:58 chatbry volumio[1135]: info: sendMpdCommand status took 5 milliseconds oct. 11 12:26:58 chatbry volumio[1135]: info: sendMpdCommand status took 4 milliseconds oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:58 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:58 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:58 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:58 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:26:58 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:26:58 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:26:59 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:59 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:59 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:59 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:59 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:59 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:59 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:59 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:59 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:59 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:59 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:59 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:59 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:59 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:59 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:59 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:59 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:59 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:59 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:59 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:59 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:59 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:26:59 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:26:59 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:26:59 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:59 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:26:59 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:26:59 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:26:59 chatbry volumio[1135]: info: ------------------------------ 1586ms oct. 11 12:26:59 chatbry volumio[1135]: info: ------------------------------ 1586ms oct. 11 12:26:59 chatbry volumio[1135]: info: ------------------------------ 736ms oct. 11 12:26:59 chatbry volumio[1135]: info: ------------------------------ 704ms oct. 11 12:26:59 chatbry volumio[1135]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/61BB-782A oct. 11 12:26:59 chatbry volumio[1135]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/host0/target0:0:0/0:0:0:0/block/sdb/sdb1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-id/usb-0424_2514-part1 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.1-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/host0/target0:0:0/0:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"36","ID_BUS":"usb","ID_FOR_SEAT":"usb-platform-3f980000_usb-usb-0_1_1","ID_MODEL":"2514","ID_MODEL_ENC":"2514","ID_MODEL_FROM_DATABASE":"USB 2.0 Hub","ID_MODEL_ID":"2514","ID_PATH":"platform-3f980000.usb-usb-0:1.1","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_1","ID_REVISION":"0bb3","ID_SERIAL":"0424_2514","ID_USB_INTERFACES":":090001:090002:","ID_USB_MODEL":"2514","ID_USB_MODEL_ENC":"2514","ID_USB_MODEL_ID":"2514","ID_USB_REVISION":"0bb3","ID_USB_SERIAL":"0424_2514","ID_USB_VENDOR":"0424","ID_USB_VENDOR_ENC":"0424","ID_USB_VENDOR_ID":"0424","ID_VENDOR":"0424","ID_VENDOR_ENC":"0424","ID_VENDOR_FROM_DATABASE":"Microchip Technology, Inc. (formerly SMSC)","ID_VENDOR_ID":"0424","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2624","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"87306127"} oct. 11 12:26:59 chatbry volumio[1135]: error: Cannot associate FS Label, not mounting oct. 11 12:26:59 chatbry volumio[1135]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN oct. 11 12:27:00 chatbry volumio[1135]: info: Mounting Device 61BB-782A oct. 11 12:27:00 chatbry sudo[1719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/61BB-782A -o noatime,dmask=0000,fmask=0000,iocharset=utf8 oct. 11 12:27:00 chatbry sudo[1719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:27:00 chatbry kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! oct. 11 12:27:00 chatbry kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. oct. 11 12:27:00 chatbry sudo[1719]: pam_unix(sudo:session): session closed for user root oct. 11 12:27:01 chatbry volumio[1135]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/61BB-782A oct. 11 12:27:01 chatbry volumio[1135]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/host0/target0:0:0/0:0:0:0/block/sdb/sdb1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-path/platform-3f980000.usb-usb-0:1.1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/61BB-782A /dev/disk/by-id/usb-USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/host0/target0:0:0/0:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"37","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"61BB-782A","ID_FS_UUID_ENC":"61BB-782A","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"SanDisk_3.2Gen1","ID_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_MODEL_ID":"5583","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"32","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"30080992","ID_PART_ENTRY_TYPE":"0xc","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-3f980000.usb-usb-0:1.1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0","ID_SERIAL_SHORT":"01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"SanDisk_3.2Gen1","ID_USB_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_USB_MODEL_ID":"5583","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"USB_SanDisk_3.2Gen1_01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476-0:0","ID_USB_SERIAL_SHORT":"01013b4448e2dcc08568365f6cd7151c03b2c387959f52d735ad3896060b8aff90b3000000000000000000005401fdabff0b4a008355810717b0c476","ID_USB_TYPE":"disk","ID_USB_VENDOR":"USB","ID_USB_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_USB_VENDOR_ID":"0781","ID_VENDOR":"USB","ID_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2664","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"89235008"} oct. 11 12:27:01 chatbry sudo[1723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 oct. 11 12:27:01 chatbry sudo[1723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:27:01 chatbry systemd[1]: media-61BB\x2d782A.mount: Deactivated successfully. oct. 11 12:27:01 chatbry sudo[1723]: pam_unix(sudo:session): session closed for user root oct. 11 12:27:01 chatbry volumio-remote-updater[637]: Test mode disabled oct. 11 12:27:01 chatbry volumio-remote-updater[637]: Alpha mode disabled oct. 11 12:27:01 chatbry volumio-remote-updater[637]: Alpha legacy test mode disabled oct. 11 12:27:01 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled oct. 11 12:27:01 chatbry volumio[1135]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} oct. 11 12:27:01 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache oct. 11 12:27:01 chatbry volumio[1135]: info: Scanning removed location : "USB/61BB-782A" oct. 11 12:27:02 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings oct. 11 12:27:02 chatbry volumio[1135]: info: Mounting Device 61BB-782A oct. 11 12:27:02 chatbry sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/61BB-782A -o noatime,dmask=0000,fmask=0000,iocharset=utf8 oct. 11 12:27:02 chatbry sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) oct. 11 12:27:02 chatbry kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! oct. 11 12:27:02 chatbry kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. oct. 11 12:27:02 chatbry sudo[1736]: pam_unix(sudo:session): session closed for user root oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:03 chatbry volumio[1135]: info: oct. 11 12:27:03 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:27:03 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:03 chatbry volumio[1135]: info: oct. 11 12:27:03 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:27:03 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:03 chatbry volumio[1135]: info: oct. 11 12:27:03 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:03 chatbry volumio[1135]: info: oct. 11 12:27:03 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:27:03 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:03 chatbry volumio[1135]: info: sendMpdCommand status took 59 milliseconds oct. 11 12:27:03 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:03 chatbry volumio[1135]: info: sendMpdCommand status took 59 milliseconds oct. 11 12:27:03 chatbry volumio[1135]: info: sendMpdCommand status took 59 milliseconds oct. 11 12:27:03 chatbry volumio[1135]: info: sendMpdCommand status took 58 milliseconds oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:03 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:27:03 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:03 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:27:03 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:03 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:27:03 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:27:03 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:27:03 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:03 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:27:03 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:03 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:27:03 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:27:03 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:27:03 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:27:03 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:03 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:27:03 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:03 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:27:03 chatbry volumio[1135]: info: ------------------------------ 491ms oct. 11 12:27:03 chatbry volumio[1135]: info: ------------------------------ 493ms oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:03 chatbry volumio[1135]: info: oct. 11 12:27:03 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:27:03 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:03 chatbry volumio[1135]: info: oct. 11 12:27:03 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:27:03 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:03 chatbry volumio[1135]: info: sendMpdCommand status took 690 milliseconds oct. 11 12:27:03 chatbry volumio[1135]: info: sendMpdCommand status took 647 milliseconds oct. 11 12:27:03 chatbry volumio[1135]: info: sendMpdCommand status took 647 milliseconds oct. 11 12:27:03 chatbry volumio[1135]: info: sendMpdCommand status took 651 milliseconds oct. 11 12:27:03 chatbry volumio[1135]: info: sendMpdCommand status took 49 milliseconds oct. 11 12:27:03 chatbry volumio[1135]: info: sendMpdCommand status took 37 milliseconds oct. 11 12:27:03 chatbry volumio[1135]: info: sendMpdCommand status took 33 milliseconds oct. 11 12:27:03 chatbry volumio[1135]: info: sendMpdCommand status took 20 milliseconds oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:03 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:27:03 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:03 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:27:03 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:03 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:27:03 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:03 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:04 chatbry volumio[1135]: info: Command Router : Notfying DB Updatefalse oct. 11 12:27:04 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:27:04 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:04 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:27:04 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:27:04 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:27:04 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:04 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:27:04 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:04 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:27:04 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:27:04 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:27:04 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:27:04 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:04 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:27:04 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:04 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:27:04 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:27:04 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:27:04 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:27:04 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:04 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:27:04 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:04 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:27:04 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:27:04 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:27:04 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:27:04 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:04 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:27:04 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:04 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:27:04 chatbry volumio[1135]: info: ------------------------------ 1461ms oct. 11 12:27:04 chatbry volumio[1135]: info: ------------------------------ 1409ms oct. 11 12:27:04 chatbry volumio[1135]: info: ------------------------------ 813ms oct. 11 12:27:04 chatbry volumio[1135]: info: ------------------------------ 799ms oct. 11 12:27:04 chatbry volumio[1135]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 oct. 11 12:27:04 chatbry volumio[1135]: info: MyVolumio token set successfully oct. 11 12:27:04 chatbry volumio[1135]: info: MYVOLUMIO: Adding device oct. 11 12:27:04 chatbry volumio[1135]: info: MYVOLUMIO: Evaluating Server oct. 11 12:27:04 chatbry volumio[1135]: info: AutoStart - Check #4/20 - VOLUMIO_SYSTEM_STATUS = starting oct. 11 12:27:04 chatbry volumio[1135]: info: Scanning new location : "USB/61BB-782A" oct. 11 12:27:05 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:05 chatbry volumio[1135]: info: oct. 11 12:27:05 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:27:05 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:27:05 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:05 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:05 chatbry volumio[1135]: info: oct. 11 12:27:05 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:27:05 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:27:05 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:05 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:05 chatbry volumio[1135]: info: oct. 11 12:27:05 chatbry volumio[1135]: ---------------------------- MPD announces state update: update oct. 11 12:27:05 chatbry volumio[1135]: info: ControllerMpd::getState oct. 11 12:27:05 chatbry volumio[1135]: verbose: ControllerMpd::sendMpdCommand status oct. 11 12:27:05 chatbry volumio[1135]: info: sendMpdCommand status took 60 milliseconds oct. 11 12:27:05 chatbry volumio[1135]: info: sendMpdCommand status took 22 milliseconds oct. 11 12:27:05 chatbry volumio[1135]: info: sendMpdCommand status took 22 milliseconds oct. 11 12:27:05 chatbry volumio[1135]: info: sendMpdCommand status took 36 milliseconds oct. 11 12:27:05 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:05 chatbry volumio[1135]: info: Command Router : Notfying DB Updatetrue oct. 11 12:27:05 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:05 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:05 chatbry kernel: sd 0:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s oct. 11 12:27:05 chatbry kernel: sd 0:0:0:0: [sdb] tag#0 Sense Key : 0x3 [current] oct. 11 12:27:05 chatbry kernel: sd 0:0:0:0: [sdb] tag#0 ASC=0x11 ASCQ=0x0 oct. 11 12:27:05 chatbry kernel: sd 0:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 04 14 00 00 00 20 00 oct. 11 12:27:05 chatbry kernel: critical medium error, dev sdb, sector 267264 op 0x0:(READ) flags 0x80700 phys_seg 4 prio class 3 oct. 11 12:27:05 chatbry volumio[1135]: info: Command Router : Notfying DB Updatetrue oct. 11 12:27:05 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:27:05 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:05 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:27:05 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:27:05 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:27:05 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:05 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:27:05 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:05 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:27:05 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:27:05 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:27:05 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:27:05 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:05 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:27:05 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:05 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:27:05 chatbry volumio[1135]: info: ------------------------------ 548ms oct. 11 12:27:05 chatbry volumio[1135]: info: ------------------------------ 518ms oct. 11 12:27:06 chatbry volumio[1135]: info: MyVolumio Plan changed: premium oct. 11 12:27:06 chatbry volumio[1135]: info: [MyVolumio PluginManager] Subscribed plan changed to premium oct. 11 12:27:06 chatbry volumio[1135]: info: Removing browser output: myVolumio user plan is not superstar oct. 11 12:27:06 chatbry volumio[1135]: info: Removing audio output: oct. 11 12:27:06 chatbry volumio[1135]: info: MYVOLUMIO: Adding device oct. 11 12:27:06 chatbry volumio[1135]: info: MYVOLUMIO: Evaluating Server oct. 11 12:27:06 chatbry volumio[1135]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/61BB-782A oct. 11 12:27:06 chatbry kernel: sd 0:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=DRIVER_OK cmd_age=0s oct. 11 12:27:06 chatbry kernel: sd 0:0:0:0: [sdb] tag#0 Sense Key : 0x3 [current] oct. 11 12:27:06 chatbry kernel: sd 0:0:0:0: [sdb] tag#0 ASC=0x11 ASCQ=0x0 oct. 11 12:27:06 chatbry kernel: sd 0:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 2b d1 00 00 00 40 00 oct. 11 12:27:06 chatbry kernel: critical medium error, dev sdb, sector 2871552 op 0x0:(READ) flags 0x80700 phys_seg 2 prio class 3 oct. 11 12:27:06 chatbry volumio[1135]: info: sendMpdCommand status took 1592 milliseconds oct. 11 12:27:06 chatbry volumio[1135]: info: sendMpdCommand status took 1604 milliseconds oct. 11 12:27:06 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:06 chatbry volumio[1135]: info: Command Router : Notfying DB Updatetrue oct. 11 12:27:06 chatbry volumio[1135]: info: CoreCommandRouter::Close All Modals sent oct. 11 12:27:07 chatbry volumio[1135]: verbose: ControllerMpd::parseState oct. 11 12:27:07 chatbry volumio[1135]: info: ControllerMpd::pushState oct. 11 12:27:07 chatbry volumio[1135]: info: CoreCommandRouter::servicePushState oct. 11 12:27:07 chatbry volumio[1135]: info: CoreStateMachine::pushState oct. 11 12:27:07 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:07 chatbry volumio[1135]: info: CoreCommandRouter::volumioPushState oct. 11 12:27:07 chatbry volumio[1135]: info: CorePlayQueue::getTrack 0 oct. 11 12:27:07 chatbry volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd oct. 11 12:27:07 chatbry volumio[1135]: info: ------------------------------ 1905ms oct. 11 12:27:08 chatbry volumio[1135]: info: Setting Geolocation for MyVolumio to eu2 oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::volumioGetBrowseSources oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion oct. 11 12:27:08 chatbry volumio[1135]: info: Setting Geolocation for MyVolumio to eu2 oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:27:08 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:27:09 chatbry volumio[1135]: info: Scanning removed location : "USB/61BB-782A" oct. 11 12:27:09 chatbry volumio[1135]: info: Remote config written successfully oct. 11 12:27:09 chatbry volumio[1135]: info: Starting Tunnel 1 oct. 11 12:27:09 chatbry volumio[1135]: info: Starting Tunnel Connection Checker oct. 11 12:27:09 chatbry volumio[1135]: info: Completed starting MyVolumio Plugin oct. 11 12:27:09 chatbry volumio[1135]: info: Successfully Added MyVolumio device oct. 11 12:27:09 chatbry volumio[1135]: info: MYVolumio Device enabled oct. 11 12:27:09 chatbry volumio[1135]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... oct. 11 12:27:09 chatbry volumio[1135]: info: Updating MyVolumio device info oct. 11 12:27:09 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:27:09 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:27:09 chatbry volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam oct. 11 12:27:09 chatbry volumio[1135]: [2026-10-11T10:27:09.930Z] @firebase/database: FIREBASE WARNING: Exception was thrown by user callback. TypeError: Cannot read properties of null (reading 'replace') oct. 11 12:27:09 chatbry volumio[1135]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) oct. 11 12:27:09 chatbry volumio[1135]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) oct. 11 12:27:09 chatbry volumio[1135]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) oct. 11 12:27:09 chatbry volumio[1135]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 oct. 11 12:27:09 chatbry volumio[1135]: at /volumio/node_modules/v-conf/index.js:166:13 oct. 11 12:27:09 chatbry volumio[1135]: at /volumio/node_modules/multimap/index.js:140:9 oct. 11 12:27:09 chatbry volumio[1135]: at Array.forEach () oct. 11 12:27:09 chatbry volumio[1135]: at /volumio/node_modules/multimap/index.js:139:13 oct. 11 12:27:09 chatbry volumio[1135]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) oct. 11 12:27:09 chatbry volumio[1135]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) oct. 11 12:27:09 chatbry volumio[1135]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10) oct. 11 12:27:09 chatbry volumio[1135]: at Config.set (/volumio/node_modules/v-conf/index.js:163:20) oct. 11 12:27:09 chatbry volumio[1135]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36229) oct. 11 12:27:09 chatbry volumio[1135]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17831 oct. 11 12:27:09 chatbry volumio[1135]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 oct. 11 12:27:09 chatbry volumio[1135]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) oct. 11 12:27:09 chatbry volumio[1135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| oct. 11 12:27:10 chatbry volumio[1135]: TypeError: Cannot read properties of null (reading 'replace') oct. 11 12:27:10 chatbry volumio[1135]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) oct. 11 12:27:10 chatbry volumio[1135]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) oct. 11 12:27:10 chatbry volumio[1135]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) oct. 11 12:27:10 chatbry volumio[1135]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 oct. 11 12:27:10 chatbry volumio[1135]: at /volumio/node_modules/v-conf/index.js:166:13 oct. 11 12:27:10 chatbry volumio[1135]: at /volumio/node_modules/multimap/index.js:140:9 oct. 11 12:27:10 chatbry volumio[1135]: at Array.forEach () oct. 11 12:27:10 chatbry volumio[1135]: at /volumio/node_modules/multimap/index.js:139:13 oct. 11 12:27:10 chatbry volumio[1135]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) oct. 11 12:27:10 chatbry volumio[1135]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) oct. 11 12:27:10 chatbry volumio[1135]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10) oct. 11 12:27:10 chatbry volumio[1135]: at Config.set (/volumio/node_modules/v-conf/index.js:163:20) oct. 11 12:27:10 chatbry volumio[1135]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36229) oct. 11 12:27:10 chatbry volumio[1135]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17831 oct. 11 12:27:10 chatbry volumio[1135]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 oct. 11 12:27:10 chatbry volumio[1135]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) oct. 11 12:27:10 chatbry volumio[1135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| oct. 11 12:27:16 chatbry sudo[1841]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 12:26' oct. 11 12:27:16 chatbry sudo[1841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"