-- Logs begin at Thu 2019-02-14 18:11:58 +08, end at Mon 2025-09-15 13:07:45 +08. -- Sep 15 13:06:13 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=3s Sep 15 13:06:13 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 15 13:06:13 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:13 volumiok kernel: Buffer I/O error on dev sda, logical block 62521328, async page read Sep 15 13:06:13 volumiok volumio-time-update[611]: volumio-time-update-util: System time updated successfully. Sep 13 22:35:47 volumiok systemd[1]: Started Time & Date Service. Sep 13 22:35:47 volumiok dbus-daemon[578]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 15 13:06:13 volumiok systemd-timedated[939]: Changed local time to Mon Sep 15 13:06:13 2025 Sep 15 13:06:13 volumiok sudo[934]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:13 volumiok systemd[1]: Started Volumio Time Update Utility. Sep 15 13:06:13 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:13 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:14 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:14 volumiok volumio[919]: info: ------------------------------------------- Sep 15 13:06:14 volumiok volumio[919]: info: ----- Volumio3 ---- Sep 15 13:06:14 volumiok volumio[919]: info: ------------------------------------------- Sep 15 13:06:14 volumiok volumio[919]: info: ----- System startup ---- Sep 15 13:06:14 volumiok volumio[919]: info: ------------------------------------------- Sep 15 13:06:14 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:15 volumiok volumio-remote-updater[612]: [2025-09-15 13:06:15] [connect] Successful connection Sep 15 13:06:15 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:15 volumiok volumio[919]: info: MYVOLUMIO Environment detected Sep 15 13:06:15 volumiok volumio[919]: info: Plugin folders cleanup Sep 15 13:06:15 volumiok volumio[919]: info: Scanning into folder /volumio/app/plugins/ Sep 15 13:06:15 volumiok volumio[919]: info: Scanning category audio_interface Sep 15 13:06:15 volumiok volumio[919]: info: Scanning category miscellanea Sep 15 13:06:15 volumiok volumio[919]: info: Scanning category music_service Sep 15 13:06:15 volumiok volumio[919]: info: Scanning category plugins.json Sep 15 13:06:15 volumiok volumio[919]: info: Scanning category system_controller Sep 15 13:06:15 volumiok volumio[919]: info: Scanning category user_interface Sep 15 13:06:15 volumiok volumio[919]: info: Scanning into folder /data/plugins/ Sep 15 13:06:15 volumiok volumio[919]: info: Plugin folders cleanup completed Sep 15 13:06:15 volumiok volumio[919]: info: ------------------------------------------- Sep 15 13:06:15 volumiok volumio[919]: info: ----- Core plugins startup ---- Sep 15 13:06:15 volumiok volumio[919]: info: ------------------------------------------- Sep 15 13:06:15 volumiok volumio[919]: info: Loading plugins from folder /volumio/app/plugins/ Sep 15 13:06:15 volumiok volumio[919]: info: Adding plugin upnp to MyMusic Plugins Sep 15 13:06:15 volumiok volumio[919]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 15 13:06:15 volumiok volumio[919]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 15 13:06:15 volumiok volumio[919]: info: Loading plugins from folder /data/plugins/ Sep 15 13:06:15 volumiok volumio[919]: info: Loading plugin "system"... Sep 15 13:06:15 volumiok volumio[919]: info: Loading plugin "appearance"... Sep 15 13:06:15 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:15 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:06:15 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:06:15 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:15 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:06:16 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:16 volumiok volumio[919]: info: Loading plugin "network"... Sep 15 13:06:16 volumiok volumio[919]: info: Refreshing Cached IP Addresses Sep 15 13:06:16 volumiok sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 13:06:16 volumiok sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:16 volumiok sudo[954]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:16 volumiok sudo[956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 13:06:16 volumiok sudo[956]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:16 volumiok sudo[956]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:16 volumiok volumio[919]: info: Loading plugin "services"... Sep 15 13:06:16 volumiok volumio[919]: info: Loading plugin "alsa_controller"... Sep 15 13:06:16 volumiok sudo[966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 15 13:06:16 volumiok sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:16 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 13:06:16 volumiok volumio[919]: info: Loading plugin "wizard"... Sep 15 13:06:16 volumiok volumio[919]: info: Loading plugin "networkfs"... Sep 15 13:06:16 volumiok volumio[919]: info: Starting Udev Watcher for removable devices Sep 15 13:06:16 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:16 volumiok kernel: Key type cifs.spnego registered Sep 15 13:06:16 volumiok kernel: Key type cifs.idmap registered Sep 15 13:06:16 volumiok kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Sep 15 13:06:16 volumiok kernel: CIFS: Attempting to mount //192.168.1.13/Mu Sep 15 13:06:16 volumiok volumio[919]: info: Ignoring mount for partition: boot Sep 15 13:06:16 volumiok volumio[919]: info: Ignoring mount for partition: volumio Sep 15 13:06:16 volumiok volumio[919]: info: Ignoring mount for partition: volumio_data Sep 15 13:06:16 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 13:06:16 volumiok volumio[919]: info: Loading plugin "volumio_command_line_client"... Sep 15 13:06:16 volumiok volumio[919]: info: Loading plugin "upnp"... Sep 15 13:06:16 volumiok volumio[919]: info: [1757912776763] Starting Upmpd Daemon Sep 15 13:06:16 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 13:06:16 volumiok volumio[919]: info: Loading plugin "my_music"... Sep 15 13:06:16 volumiok volumio[919]: info: Loading plugin "mpd"... Sep 15 13:06:16 volumiok sudo[982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kohsk14,password=yWaonng74A,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.13/Mu /mnt/NAS/DSM624 Sep 15 13:06:16 volumiok sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:17 volumiok volumio[919]: info: Loading plugin "upnp_browser"... Sep 15 13:06:17 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:17 volumiok volumio[919]: info: Loading plugin "alarm-clock"... Sep 15 13:06:17 volumiok volumio[919]: info: Plugin airplay_emulation is not enabled Sep 15 13:06:17 volumiok volumio[919]: info: Loading plugin "last_100"... Sep 15 13:06:17 volumiok volumio[919]: info: Loading plugin "webradio"... Sep 15 13:06:17 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:17 volumiok volumio[919]: info: Loading plugin "i2s_dacs"... Sep 15 13:06:17 volumiok volumio[919]: info: I2S DAC not set, start Auto-detection Sep 15 13:06:17 volumiok volumio[919]: info: Loading plugin "volumiodiscovery"... Sep 15 13:06:17 volumiok volumio[919]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 13:06:17 volumiok volumio[919]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 13:06:17 volumiok volumio[919]: *** WARNING *** For more information see Sep 15 13:06:17 volumiok volumio[919]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 13:06:17 volumiok volumio[919]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 13:06:17 volumiok volumio[919]: *** WARNING *** For more information see Sep 15 13:06:17 volumiok volumio[919]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 15 13:06:17 volumiok volumio[919]: info: Discovery: Started advertising with name: Volumiok Sep 15 13:06:17 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 13:06:17 volumiok volumio[919]: info: Loading plugin "outputs"... Sep 15 13:06:17 volumiok volumio[919]: info: Loading plugin "albumart"... Sep 15 13:06:17 volumiok volumio[919]: info: Plugin example_plugin is not enabled Sep 15 13:06:17 volumiok volumio[919]: info: Loading plugin "inputs"... Sep 15 13:06:17 volumiok volumio[919]: info: Loading plugin "updater_comm"... Sep 15 13:06:17 volumiok node[919]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 13:06:17 volumiok node[919]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 13:06:17 volumiok node[919]: *** WARNING *** For more information see Sep 15 13:06:17 volumiok node[919]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 13:06:17 volumiok node[919]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 13:06:17 volumiok node[919]: *** WARNING *** For more information see Sep 15 13:06:18 volumiok volumio[919]: info: Plugin mpdemulation is not enabled Sep 15 13:06:18 volumiok volumio[919]: info: Loading plugin "rest_api"... Sep 15 13:06:18 volumiok volumio[919]: info: Loading plugin "websocket"... Sep 15 13:06:18 volumiok volumio[919]: info: Starting Socket.io Server version 2.3.0 Sep 15 13:06:18 volumiok volumio[919]: info: Loading i18n strings for locale en Sep 15 13:06:18 volumiok volumio[919]: Updating browse sources language Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 13:06:18 volumiok volumio[919]: Forking 3 albumart workers Sep 15 13:06:18 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::initPlayerControls Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 13:06:18 volumiok volumio[919]: Express server listening on port 3000 Sep 15 13:06:18 volumiok volumio[919]: [Metrics] WebUI: 4s 446.13ms Sep 15 13:06:18 volumiok volumio[919]: info: CoreStateMachine::resetVolumioState Sep 15 13:06:18 volumiok volumio[919]: info: CoreStateMachine::getcurrentVolume Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:06:18 volumiok volumio[919]: info: Volumio Network Manager: Network status updated: 2 Sep 15 13:06:18 volumiok volumio-remote-updater[612]: [2025-09-15 13:06:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757912775 101 Sep 15 13:06:18 volumiok volumio[919]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 15 13:06:18 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:18 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:06:18 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:06:18 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:18 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:06:18 volumiok volumio[919]: info: CoreStateMachine::setRepeat null single undefined Sep 15 13:06:18 volumiok volumio[919]: info: CoreStateMachine::pushState Sep 15 13:06:18 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::volumioPushState Sep 15 13:06:18 volumiok volumio[919]: info: CoreStateMachine::setRandom null Sep 15 13:06:18 volumiok volumio[919]: info: CoreStateMachine::pushState Sep 15 13:06:18 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::volumioPushState Sep 15 13:06:18 volumiok volumio[919]: info: VolumeController:: Volume=100 Mute =false Sep 15 13:06:18 volumiok volumio[919]: info: CoreStateMachine::pushState Sep 15 13:06:18 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::volumioPushState Sep 15 13:06:18 volumiok volumio[919]: info: CoreStateMachine::updateTrackBlock Sep 15 13:06:18 volumiok volumio[919]: info: CorePlayQueue::getTrackBlock Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 13:06:18 volumiok volumio[919]: Starting albumart workers Sep 15 13:06:18 volumiok volumio[919]: info: Setting Device type: Raspberry PI Sep 15 13:06:18 volumiok volumio[919]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 15 13:06:18 volumiok volumio[919]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Sep 15 13:06:18 volumiok volumio[919]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 15 13:06:18 volumiok volumio[919]: Starting albumart workers Sep 15 13:06:18 volumiok volumio[919]: info: VolumeController:: Volume=100 Mute =false Sep 15 13:06:18 volumiok volumio[919]: info: CoreStateMachine::pushState Sep 15 13:06:18 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::volumioPushState Sep 15 13:06:18 volumiok volumio[919]: Starting albumart workers Sep 15 13:06:18 volumiok volumio[919]: info: Discovery: adding d072bc9f-95e7-4e0f-9dcd-6a0491230831 Sep 15 13:06:18 volumiok volumio[919]: info: Discovery: Found device Volumiok Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:18 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 13:06:18 volumiok volumio[919]: info: Discovery: Getting this device information Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:18 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:18 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 13:06:18 volumiok volumio[919]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Sep 15 13:06:19 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:19 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:19 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 15 13:06:19 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 15 13:06:19 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:19 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:19 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:20 volumiok sudo[966]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:20 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:20 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:21 volumiok systemd[1]: systemd-fsckd.service: Succeeded. Sep 15 13:06:21 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:21 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:06:21 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:06:21 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:21 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:06:21 volumiok kernel: sda: unable to read partition table Sep 15 13:06:21 volumiok sh[539]: timed out Sep 15 13:06:21 volumiok dhcpcd[575]: timed out Sep 15 13:06:21 volumiok sh[539]: dhcpcd exited Sep 15 13:06:21 volumiok dhcpcd[575]: dhcpcd exited Sep 15 13:06:21 volumiok sh[539]: ifup: failed to bring up eth0 Sep 15 13:06:21 volumiok systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Sep 15 13:06:21 volumiok systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Sep 15 13:06:21 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:22 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:22 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:23 volumiok systemd[1]: systemd-hostnamed.service: Succeeded. Sep 15 13:06:23 volumiok sudo[982]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:23 volumiok volumio[919]: info: Cannot mount NAS DSM624 at system boot, trial number 1 ,retrying in 5 seconds Sep 15 13:06:23 volumiok volumio[919]: info: Completed loading Core Plugins Sep 15 13:06:23 volumiok volumio[919]: info: Preparing to generate the ALSA configuration file Sep 15 13:06:23 volumiok kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 15 13:06:23 volumiok kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 15 13:06:23 volumiok volumio[919]: info: Asound.conf file unchanged, so no further update is needed Sep 15 13:06:23 volumiok volumio[919]: info: Output device has changed, restarting MPD Sep 15 13:06:23 volumiok sudo[1066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 13:06:23 volumiok volumio[919]: info: ___________ START PLUGINS ___________ Sep 15 13:06:23 volumiok sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:23 volumiok sudo[1066]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:23 volumiok volumio[919]: info: ControllerMpd::onStart: Initializing MPD Sep 15 13:06:23 volumiok volumio[919]: info: Creating MPD Configuration file Sep 15 13:06:23 volumiok sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 13:06:23 volumiok sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:23 volumiok sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 13:06:23 volumiok sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 13:06:23 volumiok volumio[919]: info: [1757912783195] CoreMusicLibrary::Adding element Media Servers Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 13:06:23 volumiok sudo[1072]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:23 volumiok sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 13:06:23 volumiok sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 13:06:23 volumiok volumio[919]: info: [1757912783217] CoreMusicLibrary::Adding element Last_100 Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 13:06:23 volumiok volumio[919]: info: [1757912783218] CoreMusicLibrary::Adding element Webradio Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 13:06:23 volumiok volumio[919]: info: Initializing BBC Radios Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:06:23 volumiok volumio[919]: info: Volumio Calling Home Sep 15 13:06:23 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:23 volumiok volumio[919]: info: MPD Permissions set Sep 15 13:06:23 volumiok volumio[919]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 13:06:23 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:23 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:23 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:24 volumiok volumio[919]: info: Volumio called home Sep 15 13:06:24 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:24 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:25 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:25 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:26 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:26 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:26 volumiok sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 13:06:26 volumiok sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:26 volumiok sudo[1114]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:26 volumiok sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 13:06:26 volumiok sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:26 volumiok sudo[1116]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:26 volumiok sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 15 13:06:26 volumiok sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:26 volumiok systemd[1]: Started UPnP Renderer front-end to MPD. Sep 15 13:06:26 volumiok sudo[1120]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:26 volumiok volumio[919]: info: Upmpdcli Daemon Started Sep 15 13:06:27 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:27 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:06:27 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:06:27 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:27 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:06:27 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:27 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:28 volumiok kernel: CIFS: Attempting to mount //192.168.1.13/Mu Sep 15 13:06:28 volumiok sudo[1124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kohsk14,password=yWaonng74A,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.13/Mu /mnt/NAS/DSM624 Sep 15 13:06:28 volumiok sudo[1124]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:28 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:28 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:29 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:29 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:30 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:06:30 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:06:30 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:30 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:06:30 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:30 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:31 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:31 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:32 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:32 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:32 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:06:32 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:06:32 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:32 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:06:32 volumiok kernel: sda: unable to read partition table Sep 15 13:06:33 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:33 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Sep 15 13:06:33 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 15 13:06:33 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 15 13:06:33 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:33 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:34 volumiok sudo[1124]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:34 volumiok volumio[919]: info: Cannot mount NAS DSM624 at system boot, trial number 2 ,retrying in 5 seconds Sep 15 13:06:34 volumiok kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 15 13:06:34 volumiok kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 15 13:06:34 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:34 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:37 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:38 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:38 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=4s Sep 15 13:06:38 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 15 13:06:38 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:38 volumiok kernel: Buffer I/O error on dev sda, logical block 62521328, async page read Sep 15 13:06:38 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 13:06:38 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 13:06:38 volumiok volumio[919]: info: Discovery: Getting this device information Sep 15 13:06:38 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:38 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:38 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 13:06:38 volumiok volumio[919]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Sep 15 13:06:38 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:38 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:38 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 15 13:06:38 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 15 13:06:38 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:38 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:39 volumiok sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kohsk14,password=yWaonng74A,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.13/Mu /mnt/NAS/DSM624 Sep 15 13:06:39 volumiok sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:39 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:39 volumiok kernel: CIFS: Attempting to mount //192.168.1.13/Mu Sep 15 13:06:39 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:39 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:39 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:40 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:40 volumiok volumio[919]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Sep 15 13:06:40 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:40 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:40 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 15 13:06:40 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 15 13:06:40 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:40 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:40 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 13:06:40 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 13:06:40 volumiok volumio[919]: info: Discovery: Getting this device information Sep 15 13:06:40 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:40 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:40 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 13:06:40 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:41 volumiok sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 13:06:41 volumiok sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:41 volumiok sudo[1148]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:41 volumiok sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 13:06:41 volumiok sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:41 volumiok sudo[1150]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:41 volumiok volumio[919]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 15; SM-X115 Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.51 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 15 13:06:41 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:41 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:41 volumiok volumio[919]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Sep 15 13:06:42 volumiok sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 13:06:42 volumiok sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:42 volumiok sudo[1154]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:42 volumiok sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 13:06:42 volumiok sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:42 volumiok sudo[1156]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:42 volumiok volumio[919]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 15; SM-X115 Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.51 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 15 13:06:42 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::volumioGetVisibleSources Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:42 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 15 13:06:42 volumiok volumio[919]: info: Received Get System Info Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 13:06:42 volumiok volumio[919]: info: Discovery: Getting this device information Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:42 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:42 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:42 volumiok volumio[919]: info: Listing playlists Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 15 13:06:42 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 15 13:06:42 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:43 volumiok systemd[1]: systemd-timedated.service: Succeeded. Sep 15 13:06:43 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:43 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:43 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:06:43 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:06:43 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:43 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:06:44 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 15 13:06:44 volumiok volumio[919]: info: Received Get System Info Sep 15 13:06:44 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 13:06:44 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 13:06:44 volumiok volumio[919]: info: Discovery: Getting this device information Sep 15 13:06:44 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:44 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:44 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 13:06:44 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 15 13:06:44 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:44 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:45 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:45 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:45 volumiok kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 15 13:06:45 volumiok kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 15 13:06:45 volumiok volumio[919]: info: Cannot mount NAS DSM624 at system boot, trial number 3 ,retrying in 5 seconds Sep 15 13:06:45 volumiok sudo[1143]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:45 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:46 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 15 13:06:46 volumiok volumio[919]: info: Received Get System Info Sep 15 13:06:46 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 13:06:46 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 13:06:46 volumiok volumio[919]: info: Discovery: Getting this device information Sep 15 13:06:46 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:06:46 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:06:46 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 13:06:46 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:46 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:06:46 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:06:46 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:46 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:06:46 volumiok volumio[919]: info: CoreCommandRouter::volumioGetQueue Sep 15 13:06:46 volumiok volumio[919]: info: CoreStateMachine::getQueue Sep 15 13:06:46 volumiok volumio[919]: info: CorePlayQueue::getQueue Sep 15 13:06:46 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 15 13:06:46 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:47 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:47 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:06:47 volumiok volumio[919]: info: CURURI: music-library/USB Sep 15 13:06:47 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:06:47 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:48 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:48 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:49 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:49 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:06:49 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:06:49 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:49 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:06:49 volumiok kernel: sda: unable to read partition table Sep 15 13:06:49 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:50 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:50 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:50 volumiok kernel: CIFS: Attempting to mount //192.168.1.13/Mu Sep 15 13:06:50 volumiok sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kohsk14,password=yWaonng74A,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.13/Mu /mnt/NAS/DSM624 Sep 15 13:06:50 volumiok sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:06:51 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:51 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:52 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:52 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 15 13:06:52 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:52 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:53 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 13:06:53 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:06:53 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 15 13:06:53 volumiok volumio[919]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Sep 15 13:06:53 volumiok volumio[919]: info: ------------------------------------------- Sep 15 13:06:53 volumiok volumio[919]: info: ----- MyVolumio plugins startup ---- Sep 15 13:06:53 volumiok volumio[919]: info: ------------------------------------------- Sep 15 13:06:53 volumiok volumio[919]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 15 13:06:53 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:53 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:54 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:54 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:55 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:06:55 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:06:55 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:55 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:06:55 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:55 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:55 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:06:55 volumiok volumio[919]: info: CURURI: music-library/USB Sep 15 13:06:55 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:06:56 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:56 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:56 volumiok sudo[1176]: pam_unix(sudo:session): session closed for user root Sep 15 13:06:56 volumiok volumio[919]: info: Cannot mount NAS DSM624 at system boot, trial number 4 ,retrying in 5 seconds Sep 15 13:06:56 volumiok kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 15 13:06:56 volumiok kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 15 13:06:57 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:57 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:57 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:06:57 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:06:57 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:06:57 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:06:58 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:58 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:58 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:59 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:06:59 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:00 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:00 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:07:00 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:07:00 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:07:00 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:07:00 volumiok kernel: sda: unable to read partition table Sep 15 13:07:00 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:01 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Sep 15 13:07:01 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 15 13:07:01 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 15 13:07:01 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:01 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 15 13:07:01 volumiok volumio[919]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 15 13:07:01 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 15 13:07:01 volumiok volumio[919]: info: Received Get System Version Sep 15 13:07:01 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 13:07:01 volumiok volumio[919]: info: Received Get System Info Sep 15 13:07:01 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 13:07:01 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 13:07:01 volumiok volumio[919]: info: Discovery: Getting this device information Sep 15 13:07:01 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:07:01 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:07:01 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 13:07:01 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:01 volumiok sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kohsk14,password=yWaonng74A,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.13/Mu /mnt/NAS/DSM624 Sep 15 13:07:01 volumiok sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:07:01 volumiok kernel: CIFS: Attempting to mount //192.168.1.13/Mu Sep 15 13:07:02 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 15 13:07:02 volumiok volumio[919]: info: Adding plugin bluetooth to MyMusic Plugins Sep 15 13:07:02 volumiok volumio[919]: info: Adding plugin multiroom to MyMusic Plugins Sep 15 13:07:02 volumiok volumio[919]: info: Adding plugin metavolumio to MyMusic Plugins Sep 15 13:07:02 volumiok volumio[919]: info: Adding plugin cd_controller to MyMusic Plugins Sep 15 13:07:02 volumiok volumio[919]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 15 13:07:02 volumiok volumio[919]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 15 13:07:02 volumiok volumio[919]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 15 13:07:02 volumiok volumio[919]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 15 13:07:02 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:03 volumiok volumio[919]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 15 13:07:03 volumiok volumio[919]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 15 13:07:03 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:07:03 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:07:03 volumiok volumio[919]: info: Starting MyVolumio Remote Streaming Endpoints Sep 15 13:07:03 volumiok volumio[919]: info: MyVolumio login type: Token Sep 15 13:07:03 volumiok volumio[919]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 15 13:07:03 volumiok volumio[919]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 15 13:07:04 volumiok volumio[919]: info: Starting Streaming Service Transparent Proxy Sep 15 13:07:04 volumiok volumio[919]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 15 13:07:04 volumiok volumio[919]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 15 13:07:04 volumiok volumio[919]: info: Streaming services startup Sep 15 13:07:04 volumiok volumio[919]: info: Starting Streaming Daemon Sep 15 13:07:04 volumiok sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 15 13:07:04 volumiok sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:07:04 volumiok volumio[919]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 15 13:07:04 volumiok sudo[1225]: pam_unix(sudo:session): session closed for user root Sep 15 13:07:04 volumiok volumio[919]: error: Cannot start Volumio Streaming Daemon Sep 15 13:07:04 volumiok volumio[919]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 15 13:07:04 volumiok volumio[919]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 15 13:07:04 volumiok volumio[919]: error: MyVolumio Custom Token format not valid, refreshing it Sep 15 13:07:04 volumiok volumio[919]: STREAMING PROXY: Starting server on port 3245 Sep 15 13:07:04 volumiok volumio[919]: Node JS runtime: 14 Sep 15 13:07:05 volumiok volumio[919]: info: MyVolumio login type: Token Sep 15 13:07:05 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:05 volumiok volumio[919]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 15 13:07:05 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:06 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=5s Sep 15 13:07:06 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 15 13:07:06 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:07:06 volumiok kernel: Buffer I/O error on dev sda, logical block 62521328, async page read Sep 15 13:07:06 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 13:07:06 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 13:07:06 volumiok volumio[919]: info: Discovery: Getting this device information Sep 15 13:07:06 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:07:06 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:07:06 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 13:07:06 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:06 volumiok volumio[919]: info: MyVolumio token set successfully Sep 15 13:07:06 volumiok volumio[919]: info: MYVOLUMIO: Adding device Sep 15 13:07:06 volumiok volumio[919]: info: MYVOLUMIO: Evaluating Server Sep 15 13:07:06 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:07 volumiok volumio[919]: info: MyVolumio status changed Sep 15 13:07:07 volumiok volumio[919]: info: Streaming services startup Sep 15 13:07:07 volumiok volumio[919]: info: Starting Streaming Daemon Sep 15 13:07:07 volumiok volumio[919]: info: Removing browser output: myVolumio user plan is not superstar Sep 15 13:07:07 volumiok volumio[919]: info: Removing audio output: Sep 15 13:07:07 volumiok volumio[919]: info: Stoppping Tunnel 1 Sep 15 13:07:07 volumiok sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 15 13:07:07 volumiok sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:07:07 volumiok sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 15 13:07:07 volumiok sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 13:07:07 volumiok sudo[1264]: pam_unix(sudo:session): session closed for user root Sep 15 13:07:07 volumiok volumio[919]: error: Cannot start Volumio Streaming Daemon Sep 15 13:07:07 volumiok volumio[919]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 15 13:07:07 volumiok volumio[919]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 15 13:07:07 volumiok sudo[1266]: pam_unix(sudo:session): session closed for user root Sep 15 13:07:07 volumiok volumio[919]: info: Remote SSH Stopped Sep 15 13:07:07 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:07 volumiok volumio[919]: info: Setting Geolocation for MyVolumio to as1 Sep 15 13:07:07 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:07:07 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:07:07 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:07:07 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:08 volumiok volumio[919]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 15 13:07:08 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:08 volumiok kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 15 13:07:08 volumiok kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 15 13:07:08 volumiok volumio[919]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Sep 15 13:07:08 volumiok sudo[1214]: pam_unix(sudo:session): session closed for user root Sep 15 13:07:08 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:08 volumiok volumio[919]: info: Updating MyVolumio device info Sep 15 13:07:08 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:07:09 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:09 volumiok volumio[919]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 15 13:07:09 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:10 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:10 volumiok volumio[919]: info: CURURI: music-library/NAS Sep 15 13:07:10 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:10 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:10 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:10 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:10 volumiok volumio[919]: info: CURURI: music-library/NAS Sep 15 13:07:10 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:10 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:11 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:11 volumiok volumio[919]: info: CURURI: music-library/NAS Sep 15 13:07:11 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:11 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:11 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:07:11 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:07:11 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:07:11 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:07:11 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:11 volumiok volumio[919]: info: CURURI: music-library/USB Sep 15 13:07:11 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:11 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:11 volumiok volumio[919]: info: CURURI: music-library/USB Sep 15 13:07:11 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:11 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:12 volumiok volumio[919]: info: MYVOLUMIO: Adding device Sep 15 13:07:12 volumiok volumio[919]: info: MYVOLUMIO: Evaluating Server Sep 15 13:07:12 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:12 volumiok volumio[919]: info: CURURI: music-library/USB Sep 15 13:07:12 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:12 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:12 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:12 volumiok volumio[919]: info: CURURI: music-library/USB Sep 15 13:07:12 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:12 volumiok volumio[919]: info: Setting Geolocation for MyVolumio to as1 Sep 15 13:07:12 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:07:12 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:07:12 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:07:12 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:13 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:13 volumiok volumio[919]: info: CURURI: music-library/USB Sep 15 13:07:13 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:13 volumiok volumio[919]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 15 13:07:13 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:13 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:13 volumiok volumio[919]: info: Updating MyVolumio device info Sep 15 13:07:13 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:07:14 volumiok volumio[919]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 15 13:07:14 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:14 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:07:14 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:07:14 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:07:14 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:07:14 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:15 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:15 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:15 volumiok volumio[919]: info: CURURI: music-library/USB Sep 15 13:07:15 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:15 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:15 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:15 volumiok volumio[919]: info: CURURI: music-library/USB Sep 15 13:07:15 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:16 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:16 volumiok volumio[919]: info: CURURI: music-library/USB Sep 15 13:07:16 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:16 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:16 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:17 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:17 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:07:17 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:07:17 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:07:17 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:07:17 volumiok kernel: sda: unable to read partition table Sep 15 13:07:17 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:17 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:18 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 15 13:07:18 volumiok volumio[919]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 15 13:07:18 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:07:18 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:07:18 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:18 volumiok volumio[919]: info: CURURI: music-library Sep 15 13:07:18 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:18 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:18 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:19 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:19 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:20 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:20 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:21 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:21 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:21 volumiok volumio[919]: info: CURURI: music-library Sep 15 13:07:21 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:21 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:22 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:22 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:22 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:07:22 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:07:22 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:07:22 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:07:23 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 13:07:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 15 13:07:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 15 13:07:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 15 13:07:23 volumiok volumio[919]: info: CoreCommandRouter::volumioGetBrowseSources Sep 15 13:07:23 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 13:07:23 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:23 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:24 volumiok volumio[919]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 15 13:07:24 volumiok volumio[919]: info: Completed starting MyVolumio Plugin Sep 15 13:07:24 volumiok volumio[919]: [Metrics] CommandRouter: 69s 617.17ms Sep 15 13:07:24 volumiok volumio[919]: info: CoreCommandRouter::volumiosetStartupVolume Sep 15 13:07:24 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 13:07:24 volumiok volumio[919]: info: VolumeController:: Setting startup Volume 100 Sep 15 13:07:24 volumiok volumio[919]: info: VolumeController::SetAlsaVolume100 Sep 15 13:07:24 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 13:07:24 volumiok volumio[919]: info: CoreCommandRouter::Close All Modals sent Sep 15 13:07:24 volumiok volumio[919]: info: CoreCommandRouter::Close All Modals sent Sep 15 13:07:24 volumiok volumio[919]: info: CoreStateMachine::pushState Sep 15 13:07:24 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:07:24 volumiok volumio[919]: info: CoreCommandRouter::volumioPushState Sep 15 13:07:24 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:24 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:25 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 15 13:07:25 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 15 13:07:25 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 15 13:07:25 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:25 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:07:25 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:07:25 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:07:25 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:07:25 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:26 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:26 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:27 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:27 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:28 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:28 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:07:28 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:07:28 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:07:28 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:07:28 volumiok kernel: sda: unable to read partition table Sep 15 13:07:28 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:28 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Sep 15 13:07:28 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 15 13:07:28 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 15 13:07:28 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 13:07:28 volumiok volumio[919]: info: CURURI: music-library Sep 15 13:07:28 volumiok volumio[919]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:29 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:29 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:29 volumiok kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Sep 15 13:07:29 volumiok kernel: xhci_hcd 0000:01:00.0: @0000000429518b90 00000000 00000000 0e000000 02028001 Sep 15 13:07:29 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:30 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:31 volumiok volumio[919]: info: BOOT COMPLETED Sep 15 13:07:33 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:33 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:33 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=4s Sep 15 13:07:33 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 1d cf ff 80 00 00 08 00 Sep 15 13:07:33 volumiok kernel: I/O error, dev sda, sector 500170624 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:07:33 volumiok kernel: Buffer I/O error on dev sda, logical block 62521328, async page read Sep 15 13:07:34 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:34 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:34 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:35 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:35 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:36 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 13:07:36 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 13:07:36 volumiok volumio[919]: info: Discovery: Getting this device information Sep 15 13:07:36 volumiok volumio[919]: info: CoreCommandRouter::volumioGetState Sep 15 13:07:36 volumiok volumio[919]: info: CorePlayQueue::getTrack 0 Sep 15 13:07:36 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 13:07:36 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:36 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:07:36 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:07:36 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:07:36 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:07:36 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:37 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:37 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:38 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:38 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:39 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:39 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:07:39 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:07:39 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:07:39 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:07:39 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:40 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:40 volumiok volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 13:07:40 volumiok volumio[919]: info: Retrieving Cloud Streaming UI Sep 15 13:07:40 volumiok volumio[919]: info: Getting Tidal Cloud Configuration Sep 15 13:07:40 volumiok volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 13:07:40 volumiok volumio[919]: info: Getting Qobuz Cloud Configuration Sep 15 13:07:40 volumiok volumio[919]: info: Asking plugin for UI Config Sep 15 13:07:40 volumiok volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 13:07:40 volumiok volumio[919]: info: Getting Spotify Cloud Configuration Sep 15 13:07:40 volumiok volumio[919]: info: Asking plugin for UI Config Sep 15 13:07:40 volumiok volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 13:07:40 volumiok volumio[919]: info: Saving Spotify Acccount Sep 15 13:07:40 volumiok volumio[919]: info: Got Tidal Cloud Configuration Sep 15 13:07:40 volumiok volumio[919]: info: Got it Sep 15 13:07:40 volumiok volumio[919]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 15 13:07:40 volumiok volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 13:07:40 volumiok volumio[919]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 15 13:07:40 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 15 13:07:40 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 15 13:07:40 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 13:07:40 volumiok volumio[919]: info: CoreCommandRouter::volumioGetBrowseSources Sep 15 13:07:40 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 13:07:40 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:40 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 15 13:07:40 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:41 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:41 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:42 volumiok kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=2s Sep 15 13:07:42 volumiok kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 Sep 15 13:07:42 volumiok kernel: I/O error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 15 13:07:42 volumiok kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 15 13:07:42 volumiok kernel: sda: unable to read partition table Sep 15 13:07:42 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:42 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:43 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:43 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:44 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:44 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 13:07:44 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:45 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:45 volumiok volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 15 13:07:45 volumiok volumio[919]: verbose: ControllerMpd::sendMpdCommand rescan Sep 15 13:07:45 volumiok volumio[919]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 13:07:45 volumiok volumio[919]: TypeError: Cannot read property 'then' of undefined Sep 15 13:07:45 volumiok volumio[919]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Sep 15 13:07:45 volumiok volumio[919]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1886:15) Sep 15 13:07:45 volumiok volumio[919]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Sep 15 13:07:45 volumiok volumio[919]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1087:26) Sep 15 13:07:45 volumiok volumio[919]: at Socket.emit (events.js:315:20) Sep 15 13:07:45 volumiok volumio[919]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 15 13:07:45 volumiok volumio[919]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 15 13:07:45 volumiok volumio[919]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 13:07:45 volumiok kernel: usb 1-1.4.3: reset high-speed USB device number 6 using xhci_hcd Sep 15 13:07:45 volumiok sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 13:06 Sep 15 13:07:45 volumiok sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"