-- Logs begin at Mon 2024-07-22 17:23:33 UTC, end at Fri 2024-09-06 17:38:39 UTC. -- Sep 06 17:38:12 volumio wireless.js[1336]: trying... Sep 06 17:38:12 volumio sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 06 17:38:12 volumio systemd[1]: Starting Daily apt download activities... Sep 06 17:38:12 volumio sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:12 volumio sudo[1489]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:13 volumio nmbd[700]: [2024/09/06 17:38:13.335461, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 06 17:38:13 volumio nmbd[700]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Sep 06 17:38:13 volumio nmbd[700]: [2024/09/06 17:38:13.335897, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 06 17:38:13 volumio nmbd[700]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Sep 06 17:38:13 volumio nmbd[700]: [2024/09/06 17:38:13.335987, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Sep 06 17:38:13 volumio nmbd[700]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Sep 06 17:38:13 volumio nmbd[700]: [2024/09/06 17:38:13.336089, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 06 17:38:13 volumio nmbd[700]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Sep 06 17:38:13 volumio nmbd[700]: [2024/09/06 17:38:13.336167, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Sep 06 17:38:13 volumio nmbd[700]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Sep 06 17:38:13 volumio nmbd[700]: [2024/09/06 17:38:13.336266, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 06 17:38:13 volumio nmbd[700]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Sep 06 17:38:13 volumio nmbd[700]: [2024/09/06 17:38:13.336345, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Sep 06 17:38:13 volumio nmbd[700]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Sep 06 17:38:13 volumio nmbd[700]: [2024/09/06 17:38:13.336415, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 06 17:38:13 volumio nmbd[700]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Sep 06 17:38:13 volumio nmbd[700]: [2024/09/06 17:38:13.336485, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Sep 06 17:38:13 volumio nmbd[700]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Sep 06 17:38:13 volumio nmbd[700]: [2024/09/06 17:38:13.336564, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 06 17:38:13 volumio nmbd[700]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Sep 06 17:38:13 volumio nmbd[700]: [2024/09/06 17:38:13.336643, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Sep 06 17:38:13 volumio nmbd[700]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Sep 06 17:38:13 volumio systemd[1]: apt-daily.service: Succeeded. Sep 06 17:38:13 volumio systemd[1]: Started Daily apt download activities. Sep 06 17:38:13 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Sep 06 17:38:13 volumio wireless.js[1336]: trying... Sep 06 17:38:13 volumio sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 06 17:38:13 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:13 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:14 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 06 17:38:14 volumio systemd[1]: Started Daily apt upgrade and clean activities. Sep 06 17:38:14 volumio volumio[944]: info: Discovery: A device disappeared from network Sep 06 17:38:14 volumio volumio[944]: info: Discovery: Device volumio disappeared from network Sep 06 17:38:14 volumio volumio[944]: info: Discovery: adding 40199376-e0ae-4fdb-bc60-c20525e8d23a Sep 06 17:38:14 volumio volumio[944]: info: Discovery: Found device Volumio Sep 06 17:38:14 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:14 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:14 volumio wireless.js[1336]: trying... Sep 06 17:38:14 volumio sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 06 17:38:14 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:14 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:15 volumio dhcpcd[1442]: wlan0: leased 192.168.1.183 for 86400 seconds Sep 06 17:38:15 volumio avahi-daemon[626]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.183. Sep 06 17:38:15 volumio dhcpcd[1442]: wlan0: adding route to 192.168.1.0/24 Sep 06 17:38:15 volumio dhcpcd[1442]: wlan0: adding default route via 192.168.1.1 Sep 06 17:38:15 volumio avahi-daemon[626]: New relevant interface wlan0.IPv4 for mDNS. Sep 06 17:38:15 volumio avahi-daemon[626]: Registering new address record for 192.168.1.183 on wlan0.IPv4. Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 06 17:38:15 volumio volumio[944]: info: Adding plugin bluetooth to MyMusic Plugins Sep 06 17:38:15 volumio volumio[944]: info: Adding plugin multiroom to MyMusic Plugins Sep 06 17:38:15 volumio volumio[944]: info: Adding plugin metavolumio to MyMusic Plugins Sep 06 17:38:15 volumio volumio[944]: info: Adding plugin cd_controller to MyMusic Plugins Sep 06 17:38:15 volumio volumio[944]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 06 17:38:15 volumio volumio[944]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 06 17:38:15 volumio volumio[944]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 06 17:38:15 volumio wireless.js[1336]: trying... Sep 06 17:38:15 volumio sudo[1619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 06 17:38:15 volumio sudo[1619]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:15 volumio sudo[1619]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:15 volumio wireless.js[1336]: Connected to: ----haipn.5G Sep 06 17:38:15 volumio wireless.js[1336]: ---- Sep 06 17:38:16 volumio sudo[1622]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 17:38:16 volumio sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:16 volumio sudo[1622]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:16 volumio wireless.js[1336]: ... joined AP, wlan0 IPv4 is 192.168.1.183, ipV6 is undefined Sep 06 17:38:16 volumio wireless.js[1336]: It's done! AP Sep 06 17:38:16 volumio systemd[1]: Started Wireless Services. Sep 06 17:38:16 volumio sudo[1334]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:16 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 06 17:38:16 volumio volumio[944]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 06 17:38:16 volumio volumio[944]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 06 17:38:16 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:16 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:16 volumio volumio[944]: info: Starting MyVolumio Remote Streaming Endpoints Sep 06 17:38:16 volumio volumio[944]: info: MyVolumio not started Sep 06 17:38:16 volumio volumio[944]: info: Initializing device activation check Sep 06 17:38:16 volumio volumio[944]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 06 17:38:16 volumio volumio[944]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 06 17:38:16 volumio ntpd[761]: Listen normally on 5 wlan0 192.168.1.183:123 Sep 06 17:38:16 volumio ntpd[761]: new interface(s) found: waking up resolver Sep 06 17:38:17 volumio volumio[944]: info: Starting Streaming Service Transparent Proxy Sep 06 17:38:17 volumio volumio[944]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 06 17:38:17 volumio volumio[944]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 06 17:38:17 volumio volumio[944]: info: Streaming services startup Sep 06 17:38:17 volumio volumio[944]: info: Starting Streaming Daemon Sep 06 17:38:17 volumio sudo[1630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 06 17:38:17 volumio sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:17 volumio volumio[944]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 06 17:38:17 volumio sudo[1630]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:17 volumio volumio[944]: error: Cannot start Volumio Streaming Daemon Sep 06 17:38:17 volumio volumio[944]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 06 17:38:17 volumio volumio[944]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:17 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:17 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:17 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:17 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:17 volumio volumio[944]: info: Discovery: this is already registered, 40199376-e0ae-4fdb-bc60-c20525e8d23a Sep 06 17:38:17 volumio volumio[944]: info: Discovery: Found device Volumio Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:17 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:17 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:17 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:17 volumio volumio[944]: verbose: New Socket.io Connection to 192.168.1.182:3000 from 192.168.1.48 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:17 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 06 17:38:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 06 17:38:17 volumio sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 17:38:17 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:17 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:17 volumio sudo[1645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 17:38:17 volumio sudo[1645]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:17 volumio sudo[1645]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:17 volumio volumio[944]: verbose: New Socket.io Connection to 192.168.1.182 from 192.168.1.48 UA: Mozilla/5.0 (Linux; Android 14; V2324A Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 06 17:38:18 volumio volumio[944]: STREAMING PROXY: Starting server on port 3245 Sep 06 17:38:18 volumio volumio[944]: Node JS runtime: 14 Sep 06 17:38:18 volumio sudo[1649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 17:38:18 volumio sudo[1649]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:18 volumio sudo[1649]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:18 volumio sudo[1651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 17:38:18 volumio sudo[1651]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:18 volumio sudo[1651]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:18 volumio volumio[944]: verbose: New Socket.io Connection to 192.168.1.182 from 192.168.1.48 UA: Mozilla/5.0 (Linux; Android 14; V2324A Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::volumioGetVisibleSources Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:18 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 06 17:38:18 volumio volumio[944]: info: Received Get System Info Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:18 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:18 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:18 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:18 volumio volumio[944]: info: Listing playlists Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 17:38:18 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 06 17:38:19 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 17:38:20 volumio volumio[944]: info: Received Get System Info Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:20 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:20 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:20 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:20 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 06 17:38:20 volumio volumio[944]: info: MyVolumio login type: Token Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 17:38:20 volumio volumio[944]: info: Received Get System Info Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:20 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:20 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:20 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:21 volumio volumio[944]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 06 17:38:21 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 06 17:38:21 volumio volumio[944]: info: MyVolumio login type: Token Sep 06 17:38:21 volumio volumio[944]: info: MyVolumio token set successfully Sep 06 17:38:21 volumio volumio[944]: info: MYVOLUMIO: Adding device Sep 06 17:38:21 volumio volumio[944]: info: MYVOLUMIO: Evaluating Server Sep 06 17:38:22 volumio volumio[944]: info: MyVolumio status changed Sep 06 17:38:22 volumio volumio[944]: info: Streaming services startup Sep 06 17:38:22 volumio volumio[944]: info: Starting Streaming Daemon Sep 06 17:38:22 volumio volumio[944]: info: Removing browser output: myVolumio user plan is not superstar Sep 06 17:38:22 volumio volumio[944]: info: Removing audio output: Sep 06 17:38:22 volumio volumio[944]: info: Stoppping Tunnel 1 Sep 06 17:38:22 volumio sudo[1672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 06 17:38:22 volumio sudo[1672]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:22 volumio sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 06 17:38:22 volumio sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:22 volumio sudo[1672]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:22 volumio volumio[944]: error: Cannot start Volumio Streaming Daemon Sep 06 17:38:22 volumio volumio[944]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 06 17:38:22 volumio volumio[944]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 06 17:38:22 volumio sudo[1675]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:22 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Sep 06 17:38:22 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Sep 06 17:38:22 volumio volumio[944]: info: Remote SSH Stopped Sep 06 17:38:22 volumio sudo[1678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 17:38:22 volumio sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:22 volumio sudo[1678]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:22 volumio sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 06 17:38:22 volumio sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:22 volumio sudo[1681]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:22 volumio volumio[944]: info: Setting Geolocation for MyVolumio to as1 Sep 06 17:38:22 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:22 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:22 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:23 volumio volumio[944]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 17:38:23 volumio volumio[944]: info: Received Get System Info Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:23 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:23 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 17:38:23 volumio volumio[944]: info: Received Get System Info Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:23 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:23 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:23 volumio volumio[944]: info: Updating MyVolumio device info Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:23 volumio kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci_hcd Sep 06 17:38:23 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:23 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:23 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:23 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:23 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:23 volumio kernel: scsi host0: uas Sep 06 17:38:23 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:23 volumio kernel: sda: sda1 sda2 Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:23 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:23 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:23 volumio kernel: usb 2-1: USB disconnect, device number 2 Sep 06 17:38:23 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] tag#28 uas_zap_pending 0 uas-tag 5 inflight: CMD Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] tag#28 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] tag#28 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:23 volumio kernel: sd 0:0:0:0: [sda] tag#28 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:23 volumio kernel: I/O error, dev sda, sector 264 op 0x0:(READ) flags 0x80700 phys_seg 31 prio class 2 Sep 06 17:38:23 volumio kernel: device offline error, dev sda, sector 264 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:23 volumio kernel: Buffer I/O error on dev sda, logical block 33, async page read Sep 06 17:38:23 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"27","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2355","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"107895641"} Sep 06 17:38:23 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:23 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"27","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2356","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"107898118"} Sep 06 17:38:23 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 17:38:23 volumio volumio[944]: info: Received Get System Info Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:23 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:23 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:23 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:24 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:24 volumio volumio[944]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 06 17:38:24 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 17:38:24 volumio volumio[944]: info: Received Get System Info Sep 06 17:38:24 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:24 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:24 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:24 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:24 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:24 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:24 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 06 17:38:25 volumio kernel: usb 2-1: new SuperSpeed USB device number 3 using xhci_hcd Sep 06 17:38:25 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:25 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:25 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:25 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:25 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:25 volumio kernel: scsi host0: uas Sep 06 17:38:25 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:25 volumio kernel: sda: sda1 sda2 Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:25 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:25 volumio kernel: usb 2-1: USB disconnect, device number 3 Sep 06 17:38:25 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:25 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] tag#4 uas_zap_pending 0 uas-tag 4 inflight: CMD Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] tag#8 uas_zap_pending 0 uas-tag 5 inflight: CMD Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] tag#4 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:25 volumio kernel: I/O error, dev sda, sector 136 op 0x0:(READ) flags 0x80700 phys_seg 15 prio class 2 Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] tag#8 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:25 volumio kernel: I/O error, dev sda, sector 264 op 0x0:(READ) flags 0x80700 phys_seg 31 prio class 2 Sep 06 17:38:25 volumio kernel: device offline error, dev sda, sector 136 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:25 volumio kernel: Buffer I/O error on dev sda, logical block 17, async page read Sep 06 17:38:25 volumio kernel: device offline error, dev sda, sector 136 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:25 volumio kernel: Buffer I/O error on dev sda, logical block 17, async page read Sep 06 17:38:25 volumio kernel: device offline error, dev sda, sector 136 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:25 volumio kernel: Buffer I/O error on dev sda, logical block 17, async page read Sep 06 17:38:25 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part2 /dev/disk/by-id/usb-JMicron_Tech_0000000045A9-0:0-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"28","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"Tech","ID_MODEL_ENC":"Tech\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0578","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"c61a6140-2999-03ad-5098-8671664aeb00","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"0407","ID_SERIAL":"JMicron_Tech_0000000045A9-0:0","ID_SERIAL_SHORT":"0000000045A9","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"JMicron","ID_VENDOR_ENC":"JMicron\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2392","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"109116101"} Sep 06 17:38:25 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:25 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-JMicron_Tech_0000000045A9-0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"28","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"Tech","ID_MODEL_ENC":"Tech\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0578","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"c61a6140-2999-03ad-5098-8671664aeb00","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"0407","ID_SERIAL":"JMicron_Tech_0000000045A9-0:0","ID_SERIAL_SHORT":"0000000045A9","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"JMicron","ID_VENDOR_ENC":"JMicron\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2391","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"109116067"} Sep 06 17:38:25 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:25 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 06 17:38:26 volumio kernel: usb 2-1: new SuperSpeed USB device number 4 using xhci_hcd Sep 06 17:38:26 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:26 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:26 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:26 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:26 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:26 volumio kernel: scsi host0: uas Sep 06 17:38:26 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:26 volumio kernel: sda: sda1 sda2 Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:26 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:26 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:26 volumio kernel: usb 2-1: USB disconnect, device number 4 Sep 06 17:38:26 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] tag#27 uas_zap_pending 0 uas-tag 4 inflight: CMD Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] tag#27 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] tag#0 uas_zap_pending 0 uas-tag 5 inflight: CMD Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] tag#27 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] tag#27 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:26 volumio kernel: I/O error, dev sda, sector 136 op 0x0:(READ) flags 0x80700 phys_seg 15 prio class 2 Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:26 volumio kernel: I/O error, dev sda, sector 264 op 0x0:(READ) flags 0x80700 phys_seg 31 prio class 2 Sep 06 17:38:26 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"29","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2428","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"110356226"} Sep 06 17:38:26 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:26 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"29","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2427","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"110356226"} Sep 06 17:38:26 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 17:38:26 volumio volumio[944]: info: Retrieving Cloud Streaming UI Sep 06 17:38:26 volumio volumio[944]: info: Getting Tidal Cloud Configuration Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 17:38:26 volumio volumio[944]: info: Getting Qobuz Cloud Configuration Sep 06 17:38:26 volumio volumio[944]: info: Asking plugin for UI Config Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 17:38:26 volumio volumio[944]: info: Getting Spotify Cloud Configuration Sep 06 17:38:26 volumio volumio[944]: info: Asking plugin for UI Config Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 17:38:26 volumio volumio[944]: info: Saving Spotify Acccount Sep 06 17:38:26 volumio volumio[944]: info: Got Tidal Cloud Configuration Sep 06 17:38:26 volumio volumio[944]: info: Got it Sep 06 17:38:26 volumio volumio[944]: info: Got it Sep 06 17:38:26 volumio volumio[944]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 17:38:26 volumio volumio[944]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 06 17:38:26 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::volumioGetBrowseSources Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::volumioGetBrowseSources Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::volumioGetBrowseSources Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 17:38:26 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 06 17:38:27 volumio volumio[944]: info: MYVOLUMIO: Adding device Sep 06 17:38:27 volumio volumio[944]: info: MYVOLUMIO: Evaluating Server Sep 06 17:38:27 volumio kernel: usb 2-1: new SuperSpeed USB device number 5 using xhci_hcd Sep 06 17:38:27 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Sep 06 17:38:27 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:27 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:27 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:27 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:27 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:27 volumio kernel: scsi host0: uas Sep 06 17:38:27 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:27 volumio kernel: sda: sda1 sda2 Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:27 volumio sudo[1717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Sep 06 17:38:27 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:27 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Sep 06 17:38:27 volumio volumio[944]: info: Refreshing Cached IP Addresses Sep 06 17:38:27 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:27 volumio sudo[1723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 17:38:27 volumio sudo[1723]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:27 volumio sudo[1723]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:27 volumio sudo[1725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 17:38:27 volumio sudo[1725]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:27 volumio sudo[1725]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:27 volumio systemd[1]: upmpdcli.service: Succeeded. Sep 06 17:38:27 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:27 volumio kernel: usb 2-1: USB disconnect, device number 5 Sep 06 17:38:27 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] tag#20 uas_zap_pending 0 uas-tag 1 inflight: CMD Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] tag#20 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] tag#23 uas_zap_pending 0 uas-tag 4 inflight: CMD Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] tag#23 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] tag#20 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] tag#20 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:27 volumio kernel: I/O error, dev sda, sector 264 op 0x0:(READ) flags 0x80700 phys_seg 31 prio class 2 Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] tag#23 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] tag#23 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:27 volumio kernel: Buffer I/O error on dev sda, logical block 17, async page read Sep 06 17:38:27 volumio kernel: Buffer I/O error on dev sda, logical block 17, async page read Sep 06 17:38:27 volumio kernel: Buffer I/O error on dev sda, logical block 17, async page read Sep 06 17:38:27 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:27 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part2 /dev/disk/by-id/usb-JMicron_Tech_0000000045A9-0:0-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"30","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"Tech","ID_MODEL_ENC":"Tech\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0578","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"c61a6140-2999-03ad-5098-8671664aeb00","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"0407","ID_SERIAL":"JMicron_Tech_0000000045A9-0:0","ID_SERIAL_SHORT":"0000000045A9","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"JMicron","ID_VENDOR_ENC":"JMicron\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2464","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"111646310"} Sep 06 17:38:27 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:27 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-JMicron_Tech_0000000045A9-0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"30","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"Tech","ID_MODEL_ENC":"Tech\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0578","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"c61a6140-2999-03ad-5098-8671664aeb00","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"0407","ID_SERIAL":"JMicron_Tech_0000000045A9-0:0","ID_SERIAL_SHORT":"0000000045A9","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"JMicron","ID_VENDOR_ENC":"JMicron\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2463","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"111646306"} Sep 06 17:38:27 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:27 volumio volumio[944]: info: Setting Geolocation for MyVolumio to as1 Sep 06 17:38:27 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:27 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:27 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:27 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 06 17:38:28 volumio volumio[944]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand rescan Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:28 volumio volumio[944]: info: Sep 06 17:38:28 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand rescan took 4 milliseconds Sep 06 17:38:28 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:28 volumio volumio[944]: info: Sep 06 17:38:28 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:28 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:28 volumio volumio[944]: info: Sep 06 17:38:28 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:28 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand status took 11 milliseconds Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand status took 8 milliseconds Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand status took 7 milliseconds Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand status took 6 milliseconds Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand status took 5 milliseconds Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand status took 4 milliseconds Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:28 volumio volumio[944]: info: Command Router : Notfying DB Updatetrue Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 06 17:38:28 volumio volumio[944]: info: Command Router : Notfying DB Updatetrue Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:28 volumio volumio[944]: info: Command Router : Notfying DB Updatetrue Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:28 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:28 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: No code Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:28 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: No code Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:28 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: No code Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: ------------------------------ 327ms Sep 06 17:38:28 volumio volumio[944]: info: ------------------------------ 324ms Sep 06 17:38:28 volumio volumio[944]: info: ------------------------------ 322ms Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:28 volumio volumio[944]: info: Sep 06 17:38:28 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:28 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:28 volumio volumio[944]: info: Sep 06 17:38:28 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:28 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:28 volumio volumio[944]: info: Sep 06 17:38:28 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:28 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand status took 11 milliseconds Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand status took 10 milliseconds Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand status took 9 milliseconds Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand status took 9 milliseconds Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand status took 8 milliseconds Sep 06 17:38:28 volumio volumio[944]: info: sendMpdCommand status took 6 milliseconds Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:28 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:28 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:28 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:28 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:28 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:28 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: No code Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:28 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: No code Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:28 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: No code Sep 06 17:38:28 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:28 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:28 volumio volumio[944]: info: ------------------------------ 131ms Sep 06 17:38:28 volumio volumio[944]: info: ------------------------------ 129ms Sep 06 17:38:28 volumio volumio[944]: info: ------------------------------ 128ms Sep 06 17:38:28 volumio volumio[944]: info: Updating MyVolumio device info Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:28 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 17:38:28 volumio kernel: usb 2-1: new SuperSpeed USB device number 6 using xhci_hcd Sep 06 17:38:28 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:28 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:28 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:28 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:28 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:28 volumio kernel: scsi host0: uas Sep 06 17:38:28 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:28 volumio kernel: sda: sda1 sda2 Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:28 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:28 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:28 volumio kernel: usb 2-1: USB disconnect, device number 6 Sep 06 17:38:28 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:28 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] tag#4 uas_zap_pending 0 uas-tag 3 inflight: CMD Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x28 28 00 00 00 00 48 00 00 30 00 Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] tag#5 uas_zap_pending 0 uas-tag 4 inflight: CMD Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] tag#5 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] tag#8 uas_zap_pending 0 uas-tag 5 inflight: CMD Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] tag#4 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x28 28 00 00 00 00 48 00 00 30 00 Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] tag#5 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] tag#5 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] tag#8 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:28 volumio kernel: sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:28 volumio kernel: Buffer I/O error on dev sda, logical block 9, async page read Sep 06 17:38:28 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"31","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2500","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"112895928"} Sep 06 17:38:28 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:28 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"31","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2499","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"112895928"} Sep 06 17:38:28 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:29 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:29 volumio volumio[944]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 06 17:38:29 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand rescan Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:29 volumio volumio[944]: info: Sep 06 17:38:29 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand rescan took 2 milliseconds Sep 06 17:38:29 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:29 volumio volumio[944]: info: Sep 06 17:38:29 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:29 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:29 volumio volumio[944]: info: Sep 06 17:38:29 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:29 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:29 volumio volumio[944]: info: Sep 06 17:38:29 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:29 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand status took 7 milliseconds Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand status took 6 milliseconds Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand status took 5 milliseconds Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand status took 4 milliseconds Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:29 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:29 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:29 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:29 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:29 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:29 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:29 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:29 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:29 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:29 volumio volumio[944]: info: No code Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:29 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:29 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:29 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:29 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:29 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:29 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:29 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:29 volumio volumio[944]: info: No code Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:29 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:29 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:29 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:29 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:29 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:29 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:29 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:29 volumio volumio[944]: info: No code Sep 06 17:38:29 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:29 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:29 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:29 volumio volumio[944]: info: ------------------------------ 114ms Sep 06 17:38:29 volumio volumio[944]: info: ------------------------------ 113ms Sep 06 17:38:29 volumio volumio[944]: info: ------------------------------ 112ms Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:29 volumio volumio[944]: info: Sep 06 17:38:29 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:29 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:29 volumio volumio[944]: info: Sep 06 17:38:29 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:29 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand status took 115 milliseconds Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand status took 114 milliseconds Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand status took 5 milliseconds Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Sep 06 17:38:29 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Sep 06 17:38:29 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:30 volumio kernel: usb 2-1: new SuperSpeed USB device number 7 using xhci_hcd Sep 06 17:38:30 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:30 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:30 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:30 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:30 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:30 volumio kernel: scsi host0: uas Sep 06 17:38:30 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:30 volumio kernel: sda: sda1 sda2 Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:30 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:30 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:30 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:30 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:30 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:30 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:30 volumio kernel: usb 2-1: USB disconnect, device number 7 Sep 06 17:38:30 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] tag#12 uas_zap_pending 0 uas-tag 1 inflight: CMD Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] tag#12 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] tag#8 uas_zap_pending 0 uas-tag 4 inflight: CMD Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] tag#12 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] tag#12 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:30 volumio kernel: blk_print_req_error: 8 callbacks suppressed Sep 06 17:38:30 volumio kernel: I/O error, dev sda, sector 264 op 0x0:(READ) flags 0x80700 phys_seg 31 prio class 2 Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] tag#8 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:30 volumio kernel: I/O error, dev sda, sector 136 op 0x0:(READ) flags 0x80700 phys_seg 15 prio class 2 Sep 06 17:38:30 volumio kernel: device offline error, dev sda, sector 136 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:30 volumio kernel: Buffer I/O error on dev sda, logical block 17, async page read Sep 06 17:38:30 volumio kernel: device offline error, dev sda, sector 136 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:30 volumio kernel: Buffer I/O error on dev sda, logical block 17, async page read Sep 06 17:38:30 volumio kernel: device offline error, dev sda, sector 136 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:30 volumio kernel: Buffer I/O error on dev sda, logical block 17, async page read Sep 06 17:38:30 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:30 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:30 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:30 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:30 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:30 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:30 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:30 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:30 volumio volumio[944]: info: No code Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:30 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:30 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:30 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:30 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:30 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:30 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:30 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:30 volumio volumio[944]: info: No code Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:30 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:30 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:30 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:30 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:30 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:30 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:30 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:30 volumio volumio[944]: info: No code Sep 06 17:38:30 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:30 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:30 volumio volumio[944]: info: ------------------------------ 420ms Sep 06 17:38:30 volumio volumio[944]: info: ------------------------------ 311ms Sep 06 17:38:30 volumio volumio[944]: info: ------------------------------ 309ms Sep 06 17:38:30 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:30 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:30 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"32","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2536","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"114140738"} Sep 06 17:38:30 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:30 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"32","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2535","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"114137840"} Sep 06 17:38:30 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:30 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 17:38:30 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 06 17:38:31 volumio kernel: usb 2-1: new SuperSpeed USB device number 8 using xhci_hcd Sep 06 17:38:31 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:31 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:31 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:31 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:31 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:31 volumio kernel: scsi host0: uas Sep 06 17:38:31 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:31 volumio kernel: sda: sda1 sda2 Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:31 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 17:38:31 volumio volumio[944]: 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 06 17:38:31 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:31 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:31 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:31 volumio kernel: usb 2-1: USB disconnect, device number 8 Sep 06 17:38:31 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] tag#4 uas_zap_pending 0 uas-tag 5 inflight: CMD Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] tag#4 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:31 volumio kernel: I/O error, dev sda, sector 264 op 0x0:(READ) flags 0x80700 phys_seg 31 prio class 2 Sep 06 17:38:31 volumio kernel: device offline error, dev sda, sector 264 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:31 volumio kernel: Buffer I/O error on dev sda, logical block 33, async page read Sep 06 17:38:31 volumio kernel: device offline error, dev sda, sector 264 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:31 volumio kernel: Buffer I/O error on dev sda, logical block 33, async page read Sep 06 17:38:31 volumio kernel: device offline error, dev sda, sector 264 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:31 volumio kernel: Buffer I/O error on dev sda, logical block 33, async page read Sep 06 17:38:31 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:31 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"33","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2571","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"115399179"} Sep 06 17:38:31 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:31 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part2 /dev/disk/by-id/usb-JMicron_Tech_0000000045A9-0:0-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"33","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"Tech","ID_MODEL_ENC":"Tech\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0578","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"c61a6140-2999-03ad-5098-8671664aeb00","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"0407","ID_SERIAL":"JMicron_Tech_0000000045A9-0:0","ID_SERIAL_SHORT":"0000000045A9","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"JMicron","ID_VENDOR_ENC":"JMicron\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2572","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"115396479"} Sep 06 17:38:31 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:31 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 06 17:38:32 volumio kernel: usb 2-1: new SuperSpeed USB device number 9 using xhci_hcd Sep 06 17:38:32 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:32 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:32 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:32 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:32 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:32 volumio kernel: scsi host0: uas Sep 06 17:38:32 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:32 volumio kernel: sda: sda1 sda2 Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:32 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:32 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:32 volumio kernel: usb 2-1: USB disconnect, device number 9 Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] tag#8 uas_zap_pending 0 uas-tag 1 inflight: CMD Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] tag#11 uas_zap_pending 0 uas-tag 4 inflight: CMD Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] tag#11 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] tag#8 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:32 volumio kernel: I/O error, dev sda, sector 264 op 0x0:(READ) flags 0x80700 phys_seg 31 prio class 2 Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] tag#11 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] tag#11 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:32 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"34","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2608","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"116656100"} Sep 06 17:38:32 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:32 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"34","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2607","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"116658807"} Sep 06 17:38:32 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:32 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:33 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand update Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:33 volumio volumio[944]: info: Sep 06 17:38:33 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand update took 3 milliseconds Sep 06 17:38:33 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:33 volumio volumio[944]: info: Sep 06 17:38:33 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:33 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:33 volumio volumio[944]: info: Sep 06 17:38:33 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:33 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:33 volumio volumio[944]: info: Sep 06 17:38:33 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:33 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand status took 9 milliseconds Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand status took 8 milliseconds Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand status took 7 milliseconds Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand status took 6 milliseconds Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand status took 6 milliseconds Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand status took 5 milliseconds Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:33 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:33 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:33 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:33 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:33 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:33 volumio volumio[944]: info: No code Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:33 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:33 volumio kernel: usb 2-1: new SuperSpeed USB device number 10 using xhci_hcd Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:33 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:33 volumio volumio[944]: info: No code Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:33 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:33 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:33 volumio volumio[944]: info: No code Sep 06 17:38:33 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:33 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:33 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:33 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:33 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:33 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:33 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:33 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:33 volumio kernel: scsi host0: uas Sep 06 17:38:33 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:33 volumio kernel: sda: sda1 sda2 Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:33 volumio volumio[944]: info: ------------------------------ 168ms Sep 06 17:38:33 volumio volumio[944]: info: ------------------------------ 165ms Sep 06 17:38:33 volumio volumio[944]: info: ------------------------------ 164ms Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:33 volumio volumio[944]: info: Sep 06 17:38:33 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:33 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:33 volumio volumio[944]: info: Sep 06 17:38:33 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:33 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand status took 166 milliseconds Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand status took 165 milliseconds Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand status took 4 milliseconds Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Sep 06 17:38:33 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Sep 06 17:38:33 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:33 volumio kernel: usb 2-1: USB disconnect, device number 10 Sep 06 17:38:33 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] tag#6 uas_zap_pending 0 uas-tag 1 inflight: CMD Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] tag#6 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] tag#6 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:33 volumio kernel: sd 0:0:0:0: [sda] tag#6 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:33 volumio kernel: Buffer I/O error on dev sda, logical block 33, async page read Sep 06 17:38:34 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:34 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:34 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:34 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:34 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:34 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:34 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:34 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:34 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:34 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:34 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:34 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:34 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:34 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:34 volumio volumio[944]: info: No code Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:34 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:34 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:34 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:34 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:34 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:34 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:34 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:34 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:34 volumio volumio[944]: info: No code Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:34 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:34 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:34 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:34 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:34 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:34 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:34 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:34 volumio volumio[944]: info: No code Sep 06 17:38:34 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:34 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:34 volumio volumio[944]: info: ------------------------------ 402ms Sep 06 17:38:34 volumio volumio[944]: info: ------------------------------ 241ms Sep 06 17:38:34 volumio volumio[944]: info: ------------------------------ 239ms Sep 06 17:38:34 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:34 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:34 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"35","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2643","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"117896048"} Sep 06 17:38:34 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:34 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"35","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2644","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"117896049"} Sep 06 17:38:34 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:34 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 17:38:34 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 06 17:38:35 volumio kernel: usb 2-1: new SuperSpeed USB device number 11 using xhci_hcd Sep 06 17:38:35 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:35 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:35 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:35 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:35 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:35 volumio kernel: scsi host0: uas Sep 06 17:38:35 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:35 volumio kernel: sda: sda1 sda2 Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:35 volumio kernel: usb 2-1: USB disconnect, device number 11 Sep 06 17:38:35 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] tag#19 uas_zap_pending 0 uas-tag 3 inflight: CMD Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] tag#19 CDB: opcode=0x28 28 00 00 00 00 48 00 00 30 00 Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] tag#16 uas_zap_pending 0 uas-tag 4 inflight: CMD Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] tag#16 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] tag#20 uas_zap_pending 0 uas-tag 5 inflight: CMD Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] tag#19 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] tag#20 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] tag#19 CDB: opcode=0x28 28 00 00 00 00 48 00 00 30 00 Sep 06 17:38:35 volumio kernel: blk_print_req_error: 3 callbacks suppressed Sep 06 17:38:35 volumio kernel: I/O error, dev sda, sector 72 op 0x0:(READ) flags 0x80700 phys_seg 6 prio class 2 Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] tag#16 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] tag#16 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:35 volumio kernel: I/O error, dev sda, sector 136 op 0x0:(READ) flags 0x80700 phys_seg 15 prio class 2 Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] tag#20 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] tag#20 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:35 volumio kernel: I/O error, dev sda, sector 264 op 0x0:(READ) flags 0x80700 phys_seg 31 prio class 2 Sep 06 17:38:35 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:35 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:35 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"36","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2680","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"119166348"} Sep 06 17:38:35 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:35 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"36","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2679","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"119169673"} Sep 06 17:38:35 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:35 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand rescan Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:35 volumio volumio[944]: info: Sep 06 17:38:35 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand rescan took 2 milliseconds Sep 06 17:38:35 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:35 volumio volumio[944]: info: Sep 06 17:38:35 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:35 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:35 volumio volumio[944]: info: Sep 06 17:38:35 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:35 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:35 volumio volumio[944]: info: Sep 06 17:38:35 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:35 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand status took 5 milliseconds Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand status took 4 milliseconds Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand status took 4 milliseconds Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:35 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:35 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:35 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:35 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:35 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: No code Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:35 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: No code Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:35 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: No code Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: ------------------------------ 344ms Sep 06 17:38:35 volumio volumio[944]: info: ------------------------------ 344ms Sep 06 17:38:35 volumio volumio[944]: info: ------------------------------ 342ms Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:35 volumio volumio[944]: info: Sep 06 17:38:35 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:35 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:35 volumio volumio[944]: info: Sep 06 17:38:35 volumio volumio[944]: ---------------------------- MPD announces state update: update Sep 06 17:38:35 volumio volumio[944]: info: ControllerMpd::getState Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand status took 347 milliseconds Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand status took 346 milliseconds Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand status took 6 milliseconds Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand status took 5 milliseconds Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand status took 4 milliseconds Sep 06 17:38:35 volumio volumio[944]: info: sendMpdCommand status took 4 milliseconds Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:35 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:35 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:35 volumio volumio[944]: info: Command Router : Notfying DB Updatefalse Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::Close All Modals sent Sep 06 17:38:35 volumio volumio[944]: verbose: ControllerMpd::parseState Sep 06 17:38:35 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:35 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: No code Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:35 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: No code Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: ControllerMpd::pushState Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::servicePushState Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 17:38:35 volumio volumio[944]: verbose: CURRENT POSITION 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: No code Sep 06 17:38:35 volumio volumio[944]: info: CoreStateMachine::pushState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Sep 06 17:38:35 volumio volumio[944]: info: ------------------------------ 457ms Sep 06 17:38:35 volumio volumio[944]: info: ------------------------------ 116ms Sep 06 17:38:35 volumio volumio[944]: info: ------------------------------ 115ms Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:35 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:35 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:35 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:35 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 17:38:36 volumio volumio[944]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 06 17:38:36 volumio volumio[944]: info: Received Get System Version Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 17:38:36 volumio volumio[944]: info: Received Get System Info Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 17:38:36 volumio volumio[944]: info: Discovery: Getting this device information Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Sep 06 17:38:36 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 17:38:36 volumio kernel: usb 2-1: new SuperSpeed USB device number 12 using xhci_hcd Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::volumioGetBrowseSources Sep 06 17:38:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 17:38:36 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:36 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:36 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:36 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:36 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:36 volumio kernel: scsi host0: uas Sep 06 17:38:36 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:36 volumio kernel: sda: sda1 sda2 Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:36 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:36 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:36 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"37","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2715","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"120466272"} Sep 06 17:38:36 volumio kernel: usb 2-1: USB disconnect, device number 12 Sep 06 17:38:36 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] tag#12 uas_zap_pending 0 uas-tag 4 inflight: CMD Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] tag#12 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] tag#16 uas_zap_pending 0 uas-tag 5 inflight: CMD Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] tag#16 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] tag#12 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] tag#12 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:36 volumio kernel: I/O error, dev sda, sector 136 op 0x0:(READ) flags 0x80700 phys_seg 15 prio class 2 Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] tag#16 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] tag#16 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:36 volumio kernel: I/O error, dev sda, sector 264 op 0x0:(READ) flags 0x80700 phys_seg 31 prio class 2 Sep 06 17:38:36 volumio kernel: device offline error, dev sda, sector 136 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:36 volumio kernel: Buffer I/O error on dev sda, logical block 17, async page read Sep 06 17:38:36 volumio kernel: device offline error, dev sda, sector 136 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:36 volumio kernel: Buffer I/O error on dev sda, logical block 17, async page read Sep 06 17:38:36 volumio kernel: device offline error, dev sda, sector 136 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:36 volumio kernel: Buffer I/O error on dev sda, logical block 17, async page read Sep 06 17:38:36 volumio kernel: device offline error, dev sda, sector 0 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Sep 06 17:38:36 volumio kernel: device offline error, dev sda, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Sep 06 17:38:36 volumio kernel: Buffer I/O error on dev sda, logical block 0, async page read Sep 06 17:38:36 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:36 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"37","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2716","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"120465557"} Sep 06 17:38:36 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:36 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 06 17:38:37 volumio kernel: usb 2-1: new SuperSpeed USB device number 13 using xhci_hcd Sep 06 17:38:37 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:37 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:37 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:37 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:37 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:37 volumio kernel: scsi host0: uas Sep 06 17:38:37 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:37 volumio kernel: sda: sda1 sda2 Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:37 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:37 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:37 volumio kernel: usb 2-1: USB disconnect, device number 13 Sep 06 17:38:37 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:37 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] tag#1 uas_zap_pending 0 uas-tag 1 inflight: CMD Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] tag#1 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] tag#3 uas_zap_pending 0 uas-tag 3 inflight: CMD Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] tag#3 CDB: opcode=0x28 28 00 00 00 00 48 00 00 30 00 Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] tag#0 uas_zap_pending 0 uas-tag 4 inflight: CMD Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] tag#1 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] tag#1 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] tag#3 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] tag#3 CDB: opcode=0x28 28 00 00 00 00 48 00 00 30 00 Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 Sep 06 17:38:37 volumio kernel: Buffer I/O error on dev sda, logical block 9, async page read Sep 06 17:38:37 volumio kernel: Buffer I/O error on dev sda, logical block 9, async page read Sep 06 17:38:37 volumio kernel: Buffer I/O error on dev sda, logical block 9, async page read Sep 06 17:38:37 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"38","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2752","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"121666222"} Sep 06 17:38:37 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:37 volumio volumio[944]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"38","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2751","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"121666622"} Sep 06 17:38:37 volumio volumio[944]: error: Cannot associate FS Label, not mounting Sep 06 17:38:37 volumio sudo[1806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 17:38:37 volumio sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 06 17:38:37 volumio sudo[1808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 17:38:37 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:37 volumio sudo[1806]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:37 volumio sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:37 volumio sudo[1806]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:37 volumio sudo[1808]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:37 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:37 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 06 17:38:37 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root Sep 06 17:38:37 volumio volumio[944]: info: Upmpdcli Daemon Started Sep 06 17:38:38 volumio volumio[1814]: writing RSA key Sep 06 17:38:38 volumio volumio[944]: info: Disabling MyMusic plugin upnp Sep 06 17:38:38 volumio sudo[1834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 06 17:38:38 volumio sudo[1834]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 17:38:38 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 06 17:38:38 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 06 17:38:38 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 17:38:38 volumio kernel: usb 2-1: new SuperSpeed USB device number 14 using xhci_hcd Sep 06 17:38:38 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 4.07 Sep 06 17:38:38 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 06 17:38:38 volumio kernel: usb 2-1: Product: External Disk 3.0 Sep 06 17:38:38 volumio kernel: usb 2-1: Manufacturer: JMicron Sep 06 17:38:38 volumio kernel: usb 2-1: SerialNumber: 0000000045A9 Sep 06 17:38:38 volumio kernel: scsi host0: uas Sep 06 17:38:38 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Tech 0407 PQ: 0 ANSI: 6 Sep 06 17:38:38 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 06 17:38:38 volumio kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB) Sep 06 17:38:38 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 06 17:38:38 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 06 17:38:38 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Sep 06 17:38:38 volumio kernel: sd 0:0:0:0: [sda] Disabling FUA Sep 06 17:38:38 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 06 17:38:38 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Sep 06 17:38:38 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 06 17:38:38 volumio kernel: sda: sda1 sda2 Sep 06 17:38:38 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 06 17:38:38 volumio volumio[944]: info: Enabling MyMusic plugin upnp Sep 06 17:38:38 volumio volumio[944]: info: Enabling plugin upnp Sep 06 17:38:38 volumio volumio[944]: info: Loading plugin "upnp"... Sep 06 17:38:38 volumio volumio[944]: info: [1725644318972] Starting Upmpd Daemon Sep 06 17:38:38 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 17:38:38 volumio volumio[944]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 17:38:38 volumio volumio[944]: Error: listen EADDRINUSE: address already in use :::6599 Sep 06 17:38:38 volumio volumio[944]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Sep 06 17:38:38 volumio volumio[944]: at listenInCluster (net.js:1366:12) Sep 06 17:38:38 volumio volumio[944]: at Server.listen (net.js:1452:7) Sep 06 17:38:38 volumio volumio[944]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 06 17:38:38 volumio volumio[944]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 06 17:38:38 volumio volumio[944]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 06 17:38:38 volumio volumio[944]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 06 17:38:38 volumio volumio[944]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Sep 06 17:38:38 volumio volumio[944]: code: 'EADDRINUSE', Sep 06 17:38:38 volumio volumio[944]: errno: -98, Sep 06 17:38:38 volumio volumio[944]: syscall: 'listen', Sep 06 17:38:38 volumio volumio[944]: address: '::', Sep 06 17:38:38 volumio volumio[944]: port: 6599 Sep 06 17:38:38 volumio volumio[944]: } Sep 06 17:38:38 volumio volumio[944]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 17:38:39 volumio kernel: usb 2-1: USB disconnect, device number 14 Sep 06 17:38:39 volumio kernel: xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state. Sep 06 17:38:39 volumio kernel: sd 0:0:0:0: [sda] tag#4 uas_zap_pending 0 uas-tag 1 inflight: CMD Sep 06 17:38:39 volumio kernel: sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:39 volumio kernel: sd 0:0:0:0: [sda] tag#4 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Sep 06 17:38:39 volumio kernel: sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 Sep 06 17:38:39 volumio kernel: Buffer I/O error on dev sda, logical block 33, async page read Sep 06 17:38:39 volumio kernel: Buffer I/O error on dev sda, logical block 33, async page read Sep 06 17:38:39 volumio kernel: Buffer I/O error on dev sda, logical block 33, async page read Sep 06 17:38:39 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 06 17:38:39 volumio sudo[1859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 17:37 Sep 06 17:38:39 volumio sudo[1859]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"