-- Logs begin at Thu 2019-02-14 19:11:58 KST, end at Sun 2025-08-31 15:50:53 KST. -- Aug 31 15:49:00 volumio volumio[11942]: info: MyVolumio status changed Aug 31 15:49:00 volumio volumio[11942]: info: Streaming services startup Aug 31 15:49:00 volumio volumio[11942]: info: Starting Streaming Daemon Aug 31 15:49:00 volumio volumio[11942]: info: Removing browser output: myVolumio user plan is not superstar Aug 31 15:49:00 volumio volumio[11942]: info: Removing audio output: Aug 31 15:49:00 volumio volumio[11942]: info: Stoppping Tunnel 1 Aug 31 15:49:00 volumio sudo[12482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 31 15:49:00 volumio sudo[12482]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:49:00 volumio sudo[12482]: pam_unix(sudo:session): session closed for user root Aug 31 15:49:00 volumio volumio[11942]: error: Cannot start Volumio Streaming Daemon Aug 31 15:49:00 volumio volumio[11942]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 31 15:49:00 volumio volumio[11942]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 31 15:49:00 volumio sudo[12485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 31 15:49:00 volumio sudo[12485]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:49:00 volumio sudo[12485]: pam_unix(sudo:session): session closed for user root Aug 31 15:49:00 volumio volumio[11942]: info: Remote SSH Stopped Aug 31 15:49:00 volumio volumio[11942]: info: Setting Geolocation for MyVolumio to as1 Aug 31 15:49:00 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:00 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:00 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:01 volumio volumio[11942]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 31 15:49:01 volumio volumio[11942]: info: Updating MyVolumio device info Aug 31 15:49:01 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:01 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:01 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:02 volumio volumio[11942]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 31 15:49:05 volumio volumio[11942]: info: MYVOLUMIO: Adding device Aug 31 15:49:05 volumio volumio[11942]: info: MYVOLUMIO: Evaluating Server Aug 31 15:49:05 volumio volumio[11942]: info: Setting Geolocation for MyVolumio to as1 Aug 31 15:49:05 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:05 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:05 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:06 volumio volumio[11942]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 31 15:49:06 volumio volumio[11942]: info: Updating MyVolumio device info Aug 31 15:49:06 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:06 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:06 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:07 volumio volumio[11942]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 31 15:49:08 volumio volumio[11942]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/lastfm/volumio/buster/armhf Aug 31 15:49:10 volumio volumio[11942]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/lastfm/volumio/buster/armhf Aug 31 15:49:10 volumio volumio[11942]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/lastfm/volumio/buster/armhf Aug 31 15:49:10 volumio volumio[11942]: info: Folder /tmp/plugins removed Aug 31 15:49:10 volumio volumio[11942]: info: Check plugin dependencies Aug 31 15:49:10 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 15:49:10 volumio volumio[11942]: info: Checking if plugin already exists Aug 31 15:49:10 volumio volumio[11942]: info: An error occurred installing the plugin. Rolling back config Aug 31 15:49:10 volumio volumio[11942]: info: Plugin folders cleanup Aug 31 15:49:10 volumio volumio[11942]: info: Scanning into folder /volumio/app/plugins/ Aug 31 15:49:10 volumio volumio[11942]: info: Scanning category audio_interface Aug 31 15:49:10 volumio volumio[11942]: info: Scanning category miscellanea Aug 31 15:49:10 volumio volumio[11942]: info: Scanning category music_service Aug 31 15:49:10 volumio volumio[11942]: info: Scanning category plugins.json Aug 31 15:49:10 volumio volumio[11942]: info: Scanning category system_controller Aug 31 15:49:10 volumio volumio[11942]: info: Scanning category user_interface Aug 31 15:49:10 volumio volumio[11942]: info: Scanning into folder /data/plugins/ Aug 31 15:49:10 volumio volumio[11942]: info: Scanning category audio_interface Aug 31 15:49:10 volumio volumio[11942]: info: Scanning category music_service Aug 31 15:49:10 volumio volumio[11942]: info: Scanning category system_controller Aug 31 15:49:10 volumio volumio[11942]: info: Scanning category system_hardware Aug 31 15:49:10 volumio volumio[11942]: info: Scanning category user_interface Aug 31 15:49:10 volumio volumio[11942]: info: Plugin folders cleanup completed Aug 31 15:49:10 volumio volumio[11942]: info: Error: Error Aug 31 15:49:10 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 15:49:10 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:10 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 15:49:10 volumio volumio[11942]: info: Folder /tmp/plugins removed Aug 31 15:49:10 volumio volumio[11942]: info: Folder /tmp/downloaded_plugin.zip removed Aug 31 15:49:10 volumio volumio[11942]: info: Folder /tmp/downloaded_plugin.zip removed Aug 31 15:49:10 volumio volumio[11942]: info: Folder /data/temp removed Aug 31 15:49:10 volumio volumio[11942]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/lastfm/volumio/buster/armhf Aug 31 15:49:10 volumio volumio-remote-updater[651]: No test mode Aug 31 15:49:10 volumio volumio-remote-updater[651]: No alpha test mode Aug 31 15:49:10 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 31 15:49:11 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 15:49:11 volumio volumio[11942]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 31 15:49:11 volumio volumio[11942]: info: CoreCommandRouter::volumioGetState Aug 31 15:49:11 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:49:11 volumio volumio[11942]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 31 15:49:11 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 31 15:49:11 volumio volumio[11942]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/lastfm/volumio/buster/armhf Aug 31 15:49:11 volumio volumio[11942]: info: Folder /tmp/plugins removed Aug 31 15:49:11 volumio volumio[11942]: info: An error occurred installing the plugin. Rolling back config Aug 31 15:49:11 volumio volumio[11942]: info: Plugin folders cleanup Aug 31 15:49:11 volumio volumio[11942]: info: Scanning into folder /volumio/app/plugins/ Aug 31 15:49:11 volumio volumio[11942]: info: Scanning category audio_interface Aug 31 15:49:11 volumio volumio[11942]: info: Scanning category miscellanea Aug 31 15:49:11 volumio volumio[11942]: info: Scanning category music_service Aug 31 15:49:11 volumio volumio[11942]: info: Scanning category plugins.json Aug 31 15:49:11 volumio volumio[11942]: info: Scanning category system_controller Aug 31 15:49:11 volumio volumio[11942]: info: Scanning category user_interface Aug 31 15:49:11 volumio volumio[11942]: info: Scanning into folder /data/plugins/ Aug 31 15:49:11 volumio volumio[11942]: info: Scanning category audio_interface Aug 31 15:49:11 volumio volumio[11942]: info: Scanning category music_service Aug 31 15:49:11 volumio volumio[11942]: info: Scanning category system_controller Aug 31 15:49:11 volumio volumio[11942]: info: Scanning category system_hardware Aug 31 15:49:11 volumio volumio[11942]: info: Scanning category user_interface Aug 31 15:49:11 volumio volumio[11942]: info: Plugin folders cleanup completed Aug 31 15:49:11 volumio volumio[11942]: info: Error: Error Aug 31 15:49:11 volumio volumio[11942]: info: Folder /tmp/plugins removed Aug 31 15:49:11 volumio volumio[11942]: info: Folder /tmp/downloaded_plugin.zip removed Aug 31 15:49:11 volumio volumio[11942]: info: Folder /tmp/downloaded_plugin.zip removed Aug 31 15:49:11 volumio volumio[11942]: info: Folder /data/temp removed Aug 31 15:49:11 volumio dnsmasq-dhcp[936]: no address range available for DHCP request via wlan0 Aug 31 15:49:12 volumio volumio[11942]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/lastfm/volumio/buster/armhf Aug 31 15:49:12 volumio volumio[11942]: info: Folder /tmp/plugins removed Aug 31 15:49:12 volumio volumio[11942]: info: An error occurred installing the plugin. Rolling back config Aug 31 15:49:12 volumio volumio[11942]: info: Plugin folders cleanup Aug 31 15:49:12 volumio volumio[11942]: info: Scanning into folder /volumio/app/plugins/ Aug 31 15:49:12 volumio volumio[11942]: info: Scanning category audio_interface Aug 31 15:49:12 volumio volumio[11942]: info: Scanning category miscellanea Aug 31 15:49:12 volumio volumio[11942]: info: Scanning category music_service Aug 31 15:49:12 volumio volumio[11942]: info: Scanning category plugins.json Aug 31 15:49:12 volumio volumio[11942]: info: Scanning category system_controller Aug 31 15:49:12 volumio volumio[11942]: info: Scanning category user_interface Aug 31 15:49:12 volumio volumio[11942]: info: Scanning into folder /data/plugins/ Aug 31 15:49:12 volumio volumio[11942]: info: Scanning category audio_interface Aug 31 15:49:12 volumio volumio[11942]: info: Scanning category music_service Aug 31 15:49:12 volumio volumio[11942]: info: Scanning category system_controller Aug 31 15:49:12 volumio volumio[11942]: info: Scanning category system_hardware Aug 31 15:49:12 volumio volumio[11942]: info: Scanning category user_interface Aug 31 15:49:12 volumio volumio[11942]: info: Plugin folders cleanup completed Aug 31 15:49:12 volumio volumio[11942]: info: Error: Error Aug 31 15:49:12 volumio volumio[11942]: info: Folder /tmp/downloaded_plugin.zip removed Aug 31 15:49:12 volumio volumio[11942]: info: Folder /tmp/plugins removed Aug 31 15:49:12 volumio volumio[11942]: info: Folder /tmp/downloaded_plugin.zip removed Aug 31 15:49:12 volumio volumio[11942]: info: Folder /data/temp removed Aug 31 15:49:13 volumio volumio[11942]: info: CoreCommandRouter::volumioGetState Aug 31 15:49:13 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:49:15 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 15:49:15 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 15:49:16 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:49:16 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 31 15:49:16 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 31 15:49:16 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 31 15:49:16 volumio volumio[11942]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 15:49:16 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 15:49:16 volumio volumio[11942]: error: Failed to check for new versions for plugin Ferrum Streaming Control Technology: Error: Invalid argument not valid semver ('' received) Aug 31 15:49:16 volumio volumio[11942]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 31 15:49:16 volumio volumio[11942]: info: Completed starting MyVolumio Plugin Aug 31 15:49:16 volumio volumio[11942]: [Metrics] CommandRouter: 64s 510.67ms Aug 31 15:49:16 volumio volumio[11942]: info: CoreCommandRouter::volumiosetStartupVolume Aug 31 15:49:16 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 15:49:16 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 15:49:16 volumio volumio[11942]: info: CoreCommandRouter::Close All Modals sent Aug 31 15:49:16 volumio volumio[11942]: info: CoreCommandRouter::Close All Modals sent Aug 31 15:49:17 volumio kernel: usb 1-1.1: USB disconnect, device number 54 Aug 31 15:49:17 volumio kernel: sd 1:0:0:0: [sdb] Synchronizing SCSI cache Aug 31 15:49:17 volumio kernel: sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Aug 31 15:49:17 volumio volumio[11942]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/a5108676-2508-494f-99f9-f14eec6a0db4 /dev/disk/by-id/wwn-0x50014ee2b9cb0e61-part2 /dev/disk/by-uuid/4EECBEE9ECBECB0B /dev/disk/by-id/ata-WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV-part2 /dev/disk/by-partlabel/Basic\\x20data\\x20partition /dev/disk/by-label/BLANK-4TB /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0-part2","DEVNAME":"/dev/sdb2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","DEVTYPE":"partition","DISKSEQ":"77","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5400","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_FS_LABEL":"BLANK-4TB","ID_FS_LABEL_ENC":"BLANK-4TB","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4EECBEE9ECBECB0B","ID_FS_UUID_ENC":"4EECBEE9ECBECB0B","ID_MODEL":"WDC_WD40EZRZ-00GXCB0","ID_MODEL_ENC":"WDC\\x20WD40EZRZ-00GXCB0\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"Basic\\x20data\\x20partition","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"264192","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"7813771264","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"a5108676-2508-494f-99f9-f14eec6a0db4","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"24f87fea-27c5-46bd-80fb-55895cb3051b","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_1_0-scsi-0_0_0_0","ID_REVISION":"80.00A80","ID_SERIAL":"WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV","ID_SERIAL_SHORT":"WD-WCC7K0UPD3TV","ID_TYPE":"disk","ID_WWN":"0x50014ee2b9cb0e61","ID_WWN_WITH_EXTENSION":"0x50014ee2b9cb0e61","MAJOR":"8","MINOR":"18","PARTN":"2","PARTNAME":"Basic data partition","SEQNUM":"4295","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2193304374"} Aug 31 15:49:17 volumio volumio[11942]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/ata-WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/wwn-0x50014ee2b9cb0e61-part1 /dev/disk/by-partlabel/Microsoft\\x20reserved\\x20partition /dev/disk/by-partuuid/0e0c1aea-9724-42fc-b640-703f6967019b","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"77","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5400","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_MODEL":"WDC_WD40EZRZ-00GXCB0","ID_MODEL_ENC":"WDC\\x20WD40EZRZ-00GXCB0\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"Microsoft\\x20reserved\\x20partition","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"34","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"262144","ID_PART_ENTRY_TYPE":"e3c9e316-0b5c-4db8-817d-f92df00215ae","ID_PART_ENTRY_UUID":"0e0c1aea-9724-42fc-b640-703f6967019b","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"24f87fea-27c5-46bd-80fb-55895cb3051b","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_1_0-scsi-0_0_0_0","ID_REVISION":"80.00A80","ID_SERIAL":"WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV","ID_SERIAL_SHORT":"WD-WCC7K0UPD3TV","ID_TYPE":"disk","ID_WWN":"0x50014ee2b9cb0e61","ID_WWN_WITH_EXTENSION":"0x50014ee2b9cb0e61","MAJOR":"8","MINOR":"17","PARTN":"1","PARTNAME":"Microsoft reserved partition","SEQNUM":"4294","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2193329253"} Aug 31 15:49:17 volumio volumio[11942]: error: Cannot associate FS Label, not mounting Aug 31 15:49:17 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 31 15:49:17 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 15:49:18 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 31 15:49:18 volumio kernel: usb 1-1.1: new high-speed USB device number 55 using xhci_hcd Aug 31 15:49:18 volumio kernel: usb 1-1.1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 2.15 Aug 31 15:49:18 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 31 15:49:18 volumio kernel: usb 1-1.1: Product: MING Aug 31 15:49:18 volumio kernel: usb 1-1.1: Manufacturer: JMicron Aug 31 15:49:18 volumio kernel: usb 1-1.1: SerialNumber: 7401000101F0 Aug 31 15:49:18 volumio kernel: scsi host1: uas Aug 31 15:49:18 volumio kernel: scsi 1:0:0:0: Direct-Access WDC WD40 EZRZ-00GXCB0 0215 PQ: 0 ANSI: 6 Aug 31 15:49:18 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Aug 31 15:49:18 volumio kernel: sd 1:0:0:0: [sdb] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB) Aug 31 15:49:18 volumio kernel: sd 1:0:0:0: [sdb] 4096-byte physical blocks Aug 31 15:49:18 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Aug 31 15:49:18 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 5f 00 00 08 Aug 31 15:49:18 volumio kernel: sd 1:0:0:0: [sdb] Disabling FUA Aug 31 15:49:18 volumio kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Aug 31 15:49:18 volumio kernel: sd 1:0:0:0: [sdb] Preferred minimum I/O size 4096 bytes Aug 31 15:49:18 volumio kernel: sd 1:0:0:0: [sdb] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Aug 31 15:49:18 volumio kernel: sdb: sdb1 sdb2 Aug 31 15:49:18 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI disk Aug 31 15:49:18 volumio volumio[11942]: error: Cannot associate FS Label, not mounting Aug 31 15:49:18 volumio volumio[11942]: info: Mounting Device BLANK-4TB Aug 31 15:49:18 volumio volumio[11942]: /bin/mkdir: cannot create directory ‘/mnt/USB/BLANK-4TB’: File exists Aug 31 15:49:18 volumio volumio[11942]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/BLANK-4TB" Aug 31 15:49:18 volumio volumio[11942]: /bin/mkdir: cannot create directory ‘/mnt/USB/BLANK-4TB’: File exists Aug 31 15:49:18 volumio sudo[12575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/BLANK-4TB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 31 15:49:18 volumio sudo[12575]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:49:20 volumio kernel: usb 1-1.1: stat urb: status -71 Aug 31 15:49:20 volumio kernel: sd 1:0:0:0: [sdb] tag#25 data cmplt err -71 uas-tag 1 inflight: CMD Aug 31 15:49:20 volumio kernel: sd 1:0:0:0: [sdb] tag#25 CDB: opcode=0x88 88 00 00 00 00 00 00 62 0d f8 00 00 01 00 00 00 Aug 31 15:49:20 volumio kernel: usb 1-1.1: stat urb: status -71 Aug 31 15:49:21 volumio kernel: usb 1-1.1: USB disconnect, device number 55 Aug 31 15:49:21 volumio kernel: sd 1:0:0:0: [sdb] tag#25 uas_zap_pending 0 uas-tag 1 inflight: CMD Aug 31 15:49:21 volumio kernel: sd 1:0:0:0: [sdb] tag#25 CDB: opcode=0x88 88 00 00 00 00 00 00 62 0d f8 00 00 01 00 00 00 Aug 31 15:49:21 volumio kernel: sd 1:0:0:0: [sdb] tag#26 uas_zap_pending 0 uas-tag 2 inflight: CMD Aug 31 15:49:21 volumio kernel: sd 1:0:0:0: [sdb] tag#26 CDB: opcode=0x88 88 00 00 00 00 00 00 62 0e f8 00 00 01 00 00 00 Aug 31 15:49:21 volumio kernel: sd 1:0:0:0: [sdb] tag#25 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Aug 31 15:49:21 volumio kernel: sd 1:0:0:0: [sdb] tag#25 CDB: opcode=0x88 88 00 00 00 00 00 00 62 0d f8 00 00 01 00 00 00 Aug 31 15:49:21 volumio kernel: I/O error, dev sdb, sector 6426104 op 0x0:(READ) flags 0x80700 phys_seg 32 prio class 2 Aug 31 15:49:21 volumio kernel: sd 1:0:0:0: [sdb] tag#26 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s Aug 31 15:49:21 volumio kernel: sd 1:0:0:0: [sdb] tag#26 CDB: opcode=0x88 88 00 00 00 00 00 00 62 0e f8 00 00 01 00 00 00 Aug 31 15:49:21 volumio kernel: I/O error, dev sdb, sector 6426360 op 0x0:(READ) flags 0x80700 phys_seg 32 prio class 2 Aug 31 15:49:21 volumio kernel: device offline error, dev sdb, sector 6426104 op 0x0:(READ) flags 0x0 phys_seg 8 prio class 2 Aug 31 15:49:21 volumio kernel: Buffer I/O error on dev sdb2, logical block 6161912, async page read Aug 31 15:49:21 volumio kernel: Buffer I/O error on dev sdb2, logical block 6161913, async page read Aug 31 15:49:21 volumio kernel: Buffer I/O error on dev sdb2, logical block 6161914, async page read Aug 31 15:49:21 volumio kernel: Buffer I/O error on dev sdb2, logical block 6161915, async page read Aug 31 15:49:21 volumio kernel: Buffer I/O error on dev sdb2, logical block 6161916, async page read Aug 31 15:49:21 volumio kernel: Buffer I/O error on dev sdb2, logical block 6161917, async page read Aug 31 15:49:21 volumio kernel: Buffer I/O error on dev sdb2, logical block 6161918, async page read Aug 31 15:49:21 volumio kernel: Buffer I/O error on dev sdb2, logical block 6161919, async page read Aug 31 15:49:21 volumio kernel: device offline error, dev sdb, sector 0 op 0x1:(WRITE) flags 0x800 phys_seg 0 prio class 2 Aug 31 15:49:21 volumio kernel: sd 1:0:0:0: [sdb] Synchronizing SCSI cache Aug 31 15:49:21 volumio kernel: sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Aug 31 15:49:21 volumio volumio[11942]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Aug 31 15:49:21 volumio volumio[11942]: Failed to read NTFS $Bitmap: Input/output error Aug 31 15:49:21 volumio volumio[11942]: NTFS is either inconsistent, or there is a hardware fault, or it's a Aug 31 15:49:21 volumio volumio[11942]: SoftRAID/FakeRAID hardware. In the first case run chkdsk /f on Windows Aug 31 15:49:21 volumio volumio[11942]: then reboot into Windows twice. The usage of the /f parameter is very Aug 31 15:49:21 volumio volumio[11942]: important! If the device is a SoftRAID/FakeRAID then first activate Aug 31 15:49:21 volumio volumio[11942]: it and mount a different device under the /dev/mapper/ directory, (e.g. Aug 31 15:49:21 volumio volumio[11942]: /dev/mapper/nvidia_eahaabcc1). Please see the 'dmraid' documentation Aug 31 15:49:21 volumio volumio[11942]: for more details. Aug 31 15:49:21 volumio volumio[11942]: Failed to sync device /dev/sdb2: No such device Aug 31 15:49:21 volumio volumio[11942]: Failed to close volume /dev/sdb2: No such device Aug 31 15:49:21 volumio volumio[11942]: error: Failed to mount BLANK-4TB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb2" "/mnt/USB/BLANK-4TB" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 31 15:49:21 volumio volumio[11942]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Aug 31 15:49:21 volumio volumio[11942]: Failed to read NTFS $Bitmap: Input/output error Aug 31 15:49:21 volumio volumio[11942]: NTFS is either inconsistent, or there is a hardware fault, or it's a Aug 31 15:49:21 volumio volumio[11942]: SoftRAID/FakeRAID hardware. In the first case run chkdsk /f on Windows Aug 31 15:49:21 volumio volumio[11942]: then reboot into Windows twice. The usage of the /f parameter is very Aug 31 15:49:21 volumio volumio[11942]: important! If the device is a SoftRAID/FakeRAID then first activate Aug 31 15:49:21 volumio volumio[11942]: it and mount a different device under the /dev/mapper/ directory, (e.g. Aug 31 15:49:21 volumio volumio[11942]: /dev/mapper/nvidia_eahaabcc1). Please see the 'dmraid' documentation Aug 31 15:49:21 volumio volumio[11942]: for more details. Aug 31 15:49:21 volumio volumio[11942]: Failed to sync device /dev/sdb2: No such device Aug 31 15:49:21 volumio volumio[11942]: Failed to close volume /dev/sdb2: No such device Aug 31 15:49:21 volumio volumio[11942]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/ata-WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/wwn-0x50014ee2b9cb0e61-part1 /dev/disk/by-partlabel/Microsoft\\x20reserved\\x20partition /dev/disk/by-partuuid/0e0c1aea-9724-42fc-b640-703f6967019b","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"78","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5400","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_MODEL":"WDC_WD40EZRZ-00GXCB0","ID_MODEL_ENC":"WDC\\x20WD40EZRZ-00GXCB0\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"Microsoft\\x20reserved\\x20partition","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"34","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"262144","ID_PART_ENTRY_TYPE":"e3c9e316-0b5c-4db8-817d-f92df00215ae","ID_PART_ENTRY_UUID":"0e0c1aea-9724-42fc-b640-703f6967019b","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"24f87fea-27c5-46bd-80fb-55895cb3051b","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_1_0-scsi-0_0_0_0","ID_REVISION":"80.00A80","ID_SERIAL":"WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV","ID_SERIAL_SHORT":"WD-WCC7K0UPD3TV","ID_TYPE":"disk","ID_WWN":"0x50014ee2b9cb0e61","ID_WWN_WITH_EXTENSION":"0x50014ee2b9cb0e61","MAJOR":"8","MINOR":"17","PARTN":"1","PARTNAME":"Microsoft reserved partition","SEQNUM":"4331","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2257766325"} Aug 31 15:49:21 volumio volumio[11942]: error: Cannot associate FS Label, not mounting Aug 31 15:49:21 volumio volumio[11942]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","ACTION":"remove","DEVNAME":"/dev/sdb2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","DEVTYPE":"partition","DISKSEQ":"78","MAJOR":"8","MINOR":"18","PARTN":"2","PARTNAME":"Basic data partition","SEQNUM":"4332","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2260252808"} Aug 31 15:49:21 volumio volumio[11942]: error: Cannot associate FS Label, not mounting Aug 31 15:49:21 volumio sudo[12575]: pam_unix(sudo:session): session closed for user root Aug 31 15:49:21 volumio fsct-plugin[11942]: Failed to initialize device 152d:0578: IO error -> failed to read sysfs attribute /sys/bus/usb/devices/1-1.1/bConfigurationValue: couldn't parse value "" Aug 31 15:49:22 volumio kernel: usb 1-1.1: new high-speed USB device number 56 using xhci_hcd Aug 31 15:49:22 volumio kernel: usb 1-1.1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 2.15 Aug 31 15:49:22 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 31 15:49:22 volumio kernel: usb 1-1.1: Product: MING Aug 31 15:49:22 volumio kernel: usb 1-1.1: Manufacturer: JMicron Aug 31 15:49:22 volumio kernel: usb 1-1.1: SerialNumber: 7401000101F0 Aug 31 15:49:22 volumio kernel: scsi host1: uas Aug 31 15:49:22 volumio kernel: scsi 1:0:0:0: Direct-Access WDC WD40 EZRZ-00GXCB0 0215 PQ: 0 ANSI: 6 Aug 31 15:49:22 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Aug 31 15:49:22 volumio kernel: sd 1:0:0:0: [sdb] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB) Aug 31 15:49:22 volumio kernel: sd 1:0:0:0: [sdb] 4096-byte physical blocks Aug 31 15:49:22 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Aug 31 15:49:22 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 5f 00 00 08 Aug 31 15:49:22 volumio kernel: sd 1:0:0:0: [sdb] Disabling FUA Aug 31 15:49:22 volumio kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Aug 31 15:49:22 volumio kernel: sd 1:0:0:0: [sdb] Preferred minimum I/O size 4096 bytes Aug 31 15:49:22 volumio kernel: sd 1:0:0:0: [sdb] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Aug 31 15:49:22 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 3 ep 5 Aug 31 15:49:22 volumio kernel: xhci_hcd 0000:01:00.0: @000000042951c130 00000000 00000000 0e000000 03068001 Aug 31 15:49:22 volumio kernel: sdb: sdb1 sdb2 Aug 31 15:49:22 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI disk Aug 31 15:49:22 volumio volumio[11942]: error: Cannot associate FS Label, not mounting Aug 31 15:49:22 volumio volumio[11942]: info: Mounting Device BLANK-4TB Aug 31 15:49:22 volumio volumio[11942]: /bin/mkdir: cannot create directory ‘/mnt/USB/BLANK-4TB’: File exists Aug 31 15:49:22 volumio volumio[11942]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/BLANK-4TB" Aug 31 15:49:22 volumio volumio[11942]: /bin/mkdir: cannot create directory ‘/mnt/USB/BLANK-4TB’: File exists Aug 31 15:49:22 volumio sudo[12587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/BLANK-4TB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 31 15:49:22 volumio sudo[12587]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:49:25 volumio fsct-plugin[11942]: Failed to initialize device 152d:0578: IO error -> Permission denied (os error 13) Aug 31 15:49:26 volumio sudo[12587]: pam_unix(sudo:session): session closed for user root Aug 31 15:49:26 volumio volumio[11942]: ntfs-3g-mount: failed to access mountpoint /media/BLANK-4TB: Transport endpoint is not connected Aug 31 15:49:26 volumio volumio[11942]: error: Failed to mount BLANK-4TB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb2" "/mnt/USB/BLANK-4TB" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 31 15:49:26 volumio volumio[11942]: ntfs-3g-mount: failed to access mountpoint /media/BLANK-4TB: Transport endpoint is not connected Aug 31 15:49:26 volumio volumio[11942]: info: BOOT COMPLETED Aug 31 15:49:27 volumio volumio[11942]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Aug 31 15:49:27 volumio volumio[11942]: info: CoreCommandRouter::volumioGetState Aug 31 15:49:27 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:49:27 volumio volumio[11942]: info: CoreCommandRouter::volumioPause Aug 31 15:49:27 volumio volumio[11942]: info: CoreStateMachine::pause Aug 31 15:49:27 volumio volumio[11942]: info: mpdhttpout --- Volumio set on pause Aug 31 15:49:27 volumio volumio[11942]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Aug 31 15:49:29 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 31 15:49:29 volumio sudo[12610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 15:49:29 volumio sudo[12610]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:49:29 volumio systemd[1]: musicservicesshield.service: Succeeded. Aug 31 15:49:29 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Aug 31 15:49:29 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Aug 31 15:49:29 volumio systemd[1]: Stopping Music Player Daemon... Aug 31 15:49:29 volumio volumio[11942]: info: CoreCommandRouter::volumioGetState Aug 31 15:49:29 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:49:29 volumio systemd[1]: mpd.service: Succeeded. Aug 31 15:49:29 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 15:49:29 volumio systemd[1]: Starting Music Player Daemon... Aug 31 15:49:29 volumio sudo[12612]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 15:49:29 volumio sudo[12612]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:49:29 volumio sudo[12612]: pam_unix(sudo:session): session closed for user root Aug 31 15:49:30 volumio mpd[12614]: Aug 31 15:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 15:49:30 volumio systemd[1]: Started Music Player Daemon. Aug 31 15:49:30 volumio sudo[12610]: pam_unix(sudo:session): session closed for user root Aug 31 15:49:30 volumio volumio[11942]: error: updateQueue error: null Aug 31 15:49:30 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Aug 31 15:49:30 volumio volumio[12617]: cset: --> shielding system active with Aug 31 15:49:30 volumio volumio[12617]: cset: "system" cpuset of CPUSPEC(1-3) with 126 tasks running Aug 31 15:49:30 volumio volumio[12617]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Aug 31 15:49:30 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Aug 31 15:49:32 volumio volumio[11942]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/lastfm/volumio/buster/armhf Aug 31 15:49:33 volumio volumio[11942]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/lastfm/volumio/buster/armhf Aug 31 15:49:33 volumio volumio[11942]: info: Folder /tmp/plugins removed Aug 31 15:49:33 volumio volumio[11942]: info: Check plugin dependencies Aug 31 15:49:34 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 15:49:34 volumio volumio[11942]: info: Checking if plugin already exists Aug 31 15:49:34 volumio volumio[11942]: info: Rename folder Aug 31 15:49:34 volumio volumio[11942]: info: Folder /tmp/downloaded_plugin.zip removed Aug 31 15:49:34 volumio volumio[11942]: info: Move to category Aug 31 15:49:38 volumio kernel: usb 1-1.1: USB disconnect, device number 56 Aug 31 15:49:39 volumio kernel: sd 1:0:0:0: [sdb] Synchronizing SCSI cache Aug 31 15:49:39 volumio kernel: sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Aug 31 15:49:39 volumio kernel: usb 1-1.1: new high-speed USB device number 57 using xhci_hcd Aug 31 15:49:39 volumio kernel: usb 1-1.1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 2.15 Aug 31 15:49:39 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 31 15:49:39 volumio kernel: usb 1-1.1: Product: MING Aug 31 15:49:39 volumio kernel: usb 1-1.1: Manufacturer: JMicron Aug 31 15:49:39 volumio kernel: usb 1-1.1: SerialNumber: 7401000101F0 Aug 31 15:49:39 volumio kernel: scsi host1: uas Aug 31 15:49:39 volumio kernel: scsi 1:0:0:0: Direct-Access WDC WD40 EZRZ-00GXCB0 0215 PQ: 0 ANSI: 6 Aug 31 15:49:39 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Aug 31 15:49:39 volumio kernel: sd 1:0:0:0: [sdb] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB) Aug 31 15:49:39 volumio kernel: sd 1:0:0:0: [sdb] 4096-byte physical blocks Aug 31 15:49:39 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Aug 31 15:49:39 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 5f 00 00 08 Aug 31 15:49:39 volumio kernel: sd 1:0:0:0: [sdb] Disabling FUA Aug 31 15:49:39 volumio kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Aug 31 15:49:39 volumio kernel: sd 1:0:0:0: [sdb] Preferred minimum I/O size 4096 bytes Aug 31 15:49:39 volumio kernel: sd 1:0:0:0: [sdb] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Aug 31 15:49:39 volumio kernel: sdb: sdb1 sdb2 Aug 31 15:49:39 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI disk Aug 31 15:49:40 volumio volumio[11942]: info: Checking if install.sh is present Aug 31 15:49:40 volumio volumio[11942]: info: Executing install.sh Aug 31 15:49:40 volumio volumio[11942]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/ata-WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/wwn-0x50014ee2b9cb0e61-part1 /dev/disk/by-partlabel/Microsoft\\x20reserved\\x20partition /dev/disk/by-partuuid/0e0c1aea-9724-42fc-b640-703f6967019b","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"79","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5400","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_MODEL":"WDC_WD40EZRZ-00GXCB0","ID_MODEL_ENC":"WDC\\x20WD40EZRZ-00GXCB0\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"Microsoft\\x20reserved\\x20partition","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"34","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"262144","ID_PART_ENTRY_TYPE":"e3c9e316-0b5c-4db8-817d-f92df00215ae","ID_PART_ENTRY_UUID":"0e0c1aea-9724-42fc-b640-703f6967019b","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"24f87fea-27c5-46bd-80fb-55895cb3051b","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_1_0-scsi-0_0_0_0","ID_REVISION":"80.00A80","ID_SERIAL":"WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV","ID_SERIAL_SHORT":"WD-WCC7K0UPD3TV","ID_TYPE":"disk","ID_WWN":"0x50014ee2b9cb0e61","ID_WWN_WITH_EXTENSION":"0x50014ee2b9cb0e61","MAJOR":"8","MINOR":"17","PARTN":"1","PARTNAME":"Microsoft reserved partition","SEQNUM":"4368","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2261780765"} Aug 31 15:49:40 volumio volumio[11942]: error: Cannot associate FS Label, not mounting Aug 31 15:49:40 volumio volumio[11942]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/a5108676-2508-494f-99f9-f14eec6a0db4 /dev/disk/by-id/wwn-0x50014ee2b9cb0e61-part2 /dev/disk/by-uuid/4EECBEE9ECBECB0B /dev/disk/by-id/ata-WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV-part2 /dev/disk/by-partlabel/Basic\\x20data\\x20partition /dev/disk/by-label/BLANK-4TB /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0-part2","DEVNAME":"/dev/sdb2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","DEVTYPE":"partition","DISKSEQ":"79","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5400","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_FS_LABEL":"BLANK-4TB","ID_FS_LABEL_ENC":"BLANK-4TB","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4EECBEE9ECBECB0B","ID_FS_UUID_ENC":"4EECBEE9ECBECB0B","ID_MODEL":"WDC_WD40EZRZ-00GXCB0","ID_MODEL_ENC":"WDC\\x20WD40EZRZ-00GXCB0\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"Basic\\x20data\\x20partition","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"264192","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"7813771264","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"a5108676-2508-494f-99f9-f14eec6a0db4","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"24f87fea-27c5-46bd-80fb-55895cb3051b","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_1_0-scsi-0_0_0_0","ID_REVISION":"80.00A80","ID_SERIAL":"WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV","ID_SERIAL_SHORT":"WD-WCC7K0UPD3TV","ID_TYPE":"disk","ID_WWN":"0x50014ee2b9cb0e61","ID_WWN_WITH_EXTENSION":"0x50014ee2b9cb0e61","MAJOR":"8","MINOR":"18","PARTN":"2","PARTNAME":"Basic data partition","SEQNUM":"4369","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2261781818"} Aug 31 15:49:40 volumio volumio[11942]: error: Cannot associate FS Label, not mounting Aug 31 15:49:40 volumio sudo[12676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/lastfm/install.sh Aug 31 15:49:40 volumio sudo[12676]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:49:40 volumio volumio[11942]: info: Mounting Device BLANK-4TB Aug 31 15:49:40 volumio sudo[12676]: pam_unix(sudo:session): session closed for user root Aug 31 15:49:40 volumio volumio[11942]: /bin/mkdir: cannot create directory ‘/mnt/USB/BLANK-4TB’: File exists Aug 31 15:49:40 volumio volumio[11942]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/BLANK-4TB" Aug 31 15:49:40 volumio volumio[11942]: /bin/mkdir: cannot create directory ‘/mnt/USB/BLANK-4TB’: File exists Aug 31 15:49:40 volumio sudo[12686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/BLANK-4TB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 31 15:49:40 volumio sudo[12686]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:49:42 volumio fsct-plugin[11942]: Failed to initialize device 152d:0578: IO error -> Permission denied (os error 13) Aug 31 15:49:45 volumio sudo[12686]: pam_unix(sudo:session): session closed for user root Aug 31 15:49:45 volumio volumio[11942]: ntfs-3g-mount: failed to access mountpoint /media/BLANK-4TB: Transport endpoint is not connected Aug 31 15:49:45 volumio volumio[11942]: error: Failed to mount BLANK-4TB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb2" "/mnt/USB/BLANK-4TB" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 31 15:49:45 volumio volumio[11942]: ntfs-3g-mount: failed to access mountpoint /media/BLANK-4TB: Transport endpoint is not connected Aug 31 15:49:45 volumio volumio[11942]: info: Install script completed Aug 31 15:49:45 volumio volumio[11942]: info: Adding reference to registry Aug 31 15:49:45 volumio volumio[11942]: info: Done installing plugin. Aug 31 15:49:45 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 15:49:45 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 15:49:45 volumio volumio[11942]: info: Folder /tmp/plugins removed Aug 31 15:49:45 volumio volumio[11942]: info: Folder /tmp/downloaded_plugin.zip removed Aug 31 15:49:45 volumio volumio[11942]: info: Installing LastFM and its dependencies... Aug 31 15:49:45 volumio volumio[11942]: info: Downloading installation package... Aug 31 15:49:45 volumio volumio[11942]: Plugin install end detected on script Aug 31 15:49:45 volumio volumio[11942]: info: Folder /data/temp removed Aug 31 15:49:47 volumio volumio[11942]: info: Enabling plugin lastfm Aug 31 15:49:47 volumio volumio[11942]: info: Loading plugin "lastfm"... Aug 31 15:49:47 volumio volumio[11942]: info: PLUGIN START: lastfm Aug 31 15:49:47 volumio volumio[11942]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 15:49:47 volumio volumio[11942]: info: [1756622987374] CoreMusicLibrary::Adding element LastFM Aug 31 15:49:47 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 15:49:47 volumio volumio[11942]: Cannot find translation for source YouTube2 Aug 31 15:49:47 volumio volumio[11942]: Cannot find translation for source YouTube Music Aug 31 15:49:47 volumio volumio[11942]: Cannot find translation for source 개인 라디오 Aug 31 15:49:47 volumio volumio[11942]: Cannot find translation for source 팟캐스트 Aug 31 15:49:47 volumio volumio[11942]: Cannot find translation for source Radio Paradise Aug 31 15:49:47 volumio volumio[11942]: Cannot find translation for source LastFM Aug 31 15:49:47 volumio volumio[11942]: info: [LastFM] scrobbler initiated! Aug 31 15:49:47 volumio volumio[11942]: info: [LastFM] extended logging: false Aug 31 15:49:47 volumio volumio[11942]: info: [LastFM] try scrobble stream/radio plays: true Aug 31 15:49:47 volumio volumio[11942]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Aug 31 15:49:47 volumio volumio[11942]: info: [LastFM] Left init routine Aug 31 15:49:47 volumio volumio[11942]: info: [LastFM] Socket already connected: false Aug 31 15:49:47 volumio volumio[11942]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Aug 31 15:49:47 volumio volumio[11942]: info: Done. Aug 31 15:49:47 volumio volumio[11942]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 31 15:49:47 volumio volumio[11942]: error: Failed to check for new versions for plugin Ferrum Streaming Control Technology: Error: Invalid argument not valid semver ('' received) Aug 31 15:50:11 volumio dnsmasq-dhcp[936]: no address range available for DHCP request via wlan0 Aug 31 15:50:13 volumio volumio[11942]: info: Starting Uninstall of plugin audio_interface - mpdoutput Aug 31 15:50:13 volumio volumio[11942]: info: Uninstalling plugin mpdoutput Aug 31 15:50:13 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 31 15:50:13 volumio volumio[11942]: info: Disabling plugin mpdoutput Aug 31 15:50:13 volumio volumio[11942]: info: Checking if uninstall.sh is present Aug 31 15:50:13 volumio sudo[12804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 15:50:13 volumio sudo[12804]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:50:13 volumio volumio[11942]: info: Executing uninstall.sh Aug 31 15:50:13 volumio systemd[1]: musicservicesshield.service: Succeeded. Aug 31 15:50:13 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Aug 31 15:50:13 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Aug 31 15:50:13 volumio systemd[1]: Stopping Music Player Daemon... Aug 31 15:50:13 volumio volumio[11942]: info: CoreCommandRouter::volumioGetState Aug 31 15:50:13 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:13 volumio systemd[1]: mpd.service: Succeeded. Aug 31 15:50:13 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 15:50:13 volumio sudo[12808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/mpdoutput/uninstall.sh Aug 31 15:50:13 volumio sudo[12808]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:50:13 volumio volumio[11942]: info: Uninstall script completed Aug 31 15:50:13 volumio volumio[11942]: info: Removing plugin mpdoutput from configuration Aug 31 15:50:13 volumio sudo[12808]: pam_unix(sudo:session): session closed for user root Aug 31 15:50:13 volumio systemd[1]: Starting Music Player Daemon... Aug 31 15:50:13 volumio sudo[12809]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 15:50:13 volumio sudo[12809]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:50:14 volumio sudo[12809]: pam_unix(sudo:session): session closed for user root Aug 31 15:50:14 volumio volumio[11942]: info: Successfully removed mpdoutput configuration files Aug 31 15:50:14 volumio volumio[11942]: info: Plugin folders cleanup Aug 31 15:50:14 volumio volumio[11942]: info: Scanning into folder /volumio/app/plugins/ Aug 31 15:50:14 volumio volumio[11942]: info: Scanning category audio_interface Aug 31 15:50:14 volumio volumio[11942]: info: Scanning category miscellanea Aug 31 15:50:14 volumio volumio[11942]: info: Scanning category music_service Aug 31 15:50:14 volumio volumio[11942]: info: Scanning category plugins.json Aug 31 15:50:14 volumio volumio[11942]: info: Scanning category system_controller Aug 31 15:50:14 volumio volumio[11942]: info: Scanning category user_interface Aug 31 15:50:14 volumio volumio[11942]: info: Scanning into folder /data/plugins/ Aug 31 15:50:14 volumio volumio[11942]: info: Scanning category audio_interface Aug 31 15:50:14 volumio volumio[11942]: info: Cleaning folder for mpdoutput Aug 31 15:50:14 volumio mpd[12830]: Aug 31 15:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 15:50:14 volumio systemd[1]: Started Music Player Daemon. Aug 31 15:50:14 volumio sudo[12804]: pam_unix(sudo:session): session closed for user root Aug 31 15:50:14 volumio volumio[11942]: info: Scanning category music_service Aug 31 15:50:14 volumio volumio[11942]: info: Scanning category system_controller Aug 31 15:50:14 volumio volumio[11942]: info: Scanning category system_hardware Aug 31 15:50:14 volumio volumio[11942]: info: Scanning category user_interface Aug 31 15:50:14 volumio volumio[11942]: info: Plugin folders cleanup completed Aug 31 15:50:14 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 15:50:14 volumio volumio[11942]: info: [{"prettyName":"Logitech Media Server","name":"lms","category":"music_service","version":"1.2.6","icon":"fa fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"miniDLNA","name":"minidlna","category":"music_service","version":"3.1.12","icon":"fa-share-alt","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Personal Radio","name":"personal_radio","category":"music_service","version":"1.3.0","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Podcast","name":"podcast","category":"music_service","version":"2.2.0","icon":"fa-podcast","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.13","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Squeezelite MC","name":"squeezelite_mc","category":"music_service","version":"1.0.4","icon":"fa-play-circle-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.4.2","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.2.0","icon":"fa-youtube","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.3.1","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Ferrum Streaming Control Technology","name":"ferrum_streaming_control_technology","category":"user_interface","version":"1.0.1","icon":"fa-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"LastFM Scrobbler","name":"lastfm","category":"user_interface","version":"1.5.6","icon":"fa-lastfm","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.5","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":false,"active":false}] Aug 31 15:50:14 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 15:50:14 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Aug 31 15:50:14 volumio volumio[11942]: error: updateQueue error: null Aug 31 15:50:14 volumio volumio[12833]: cset: --> shielding system active with Aug 31 15:50:14 volumio volumio[12833]: cset: "system" cpuset of CPUSPEC(1-3) with 126 tasks running Aug 31 15:50:14 volumio volumio[12833]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Aug 31 15:50:14 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Aug 31 15:50:16 volumio volumio[11942]: error: Failed to check for new versions for plugin Ferrum Streaming Control Technology: Error: Invalid argument not valid semver ('' received) Aug 31 15:50:42 volumio volumio[11942]: info: CoreCommandRouter::volumioGetState Aug 31 15:50:42 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 15:50:45 volumio volumio[11942]: info: Retrieving Cloud Streaming UI Aug 31 15:50:45 volumio volumio[11942]: info: Getting Tidal Cloud Configuration Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 15:50:45 volumio volumio[11942]: info: Getting Qobuz Cloud Configuration Aug 31 15:50:45 volumio volumio[11942]: info: Asking plugin for UI Config Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 15:50:45 volumio volumio[11942]: info: Getting Spotify Cloud Configuration Aug 31 15:50:45 volumio volumio[11942]: info: Asking plugin for UI Config Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 15:50:45 volumio volumio[11942]: info: Saving Spotify Acccount Aug 31 15:50:45 volumio volumio[11942]: info: Got Tidal Cloud Configuration Aug 31 15:50:45 volumio volumio[11942]: info: Got it Aug 31 15:50:45 volumio volumio[11942]: info: Got it Aug 31 15:50:45 volumio volumio[11942]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 15:50:45 volumio volumio[11942]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 15:50:45 volumio volumio[11942]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 15:50:46 volumio kernel: usb 1-1.1: USB disconnect, device number 57 Aug 31 15:50:47 volumio kernel: sd 1:0:0:0: [sdb] Synchronizing SCSI cache Aug 31 15:50:47 volumio kernel: sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Aug 31 15:50:47 volumio volumio[11942]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/a5108676-2508-494f-99f9-f14eec6a0db4 /dev/disk/by-id/wwn-0x50014ee2b9cb0e61-part2 /dev/disk/by-uuid/4EECBEE9ECBECB0B /dev/disk/by-id/ata-WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV-part2 /dev/disk/by-partlabel/Basic\\x20data\\x20partition /dev/disk/by-label/BLANK-4TB /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0-part2","DEVNAME":"/dev/sdb2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","DEVTYPE":"partition","DISKSEQ":"80","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5400","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_FS_LABEL":"BLANK-4TB","ID_FS_LABEL_ENC":"BLANK-4TB","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4EECBEE9ECBECB0B","ID_FS_UUID_ENC":"4EECBEE9ECBECB0B","ID_MODEL":"WDC_WD40EZRZ-00GXCB0","ID_MODEL_ENC":"WDC\\x20WD40EZRZ-00GXCB0\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"Basic\\x20data\\x20partition","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"264192","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"7813771264","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"a5108676-2508-494f-99f9-f14eec6a0db4","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"24f87fea-27c5-46bd-80fb-55895cb3051b","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_1_0-scsi-0_0_0_0","ID_REVISION":"80.00A80","ID_SERIAL":"WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV","ID_SERIAL_SHORT":"WD-WCC7K0UPD3TV","ID_TYPE":"disk","ID_WWN":"0x50014ee2b9cb0e61","ID_WWN_WITH_EXTENSION":"0x50014ee2b9cb0e61","MAJOR":"8","MINOR":"18","PARTN":"2","PARTNAME":"Basic data partition","SEQNUM":"4406","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2279253845"} Aug 31 15:50:47 volumio volumio[11942]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/ata-WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/wwn-0x50014ee2b9cb0e61-part1 /dev/disk/by-partlabel/Microsoft\\x20reserved\\x20partition /dev/disk/by-partuuid/0e0c1aea-9724-42fc-b640-703f6967019b","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"80","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"66026","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5400","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_MODEL":"WDC_WD40EZRZ-00GXCB0","ID_MODEL_ENC":"WDC\\x20WD40EZRZ-00GXCB0\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"Microsoft\\x20reserved\\x20partition","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"34","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"262144","ID_PART_ENTRY_TYPE":"e3c9e316-0b5c-4db8-817d-f92df00215ae","ID_PART_ENTRY_UUID":"0e0c1aea-9724-42fc-b640-703f6967019b","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"24f87fea-27c5-46bd-80fb-55895cb3051b","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_1_0-scsi-0_0_0_0","ID_REVISION":"80.00A80","ID_SERIAL":"WDC_WD40EZRZ-00GXCB0_WD-WCC7K0UPD3TV","ID_SERIAL_SHORT":"WD-WCC7K0UPD3TV","ID_TYPE":"disk","ID_WWN":"0x50014ee2b9cb0e61","ID_WWN_WITH_EXTENSION":"0x50014ee2b9cb0e61","MAJOR":"8","MINOR":"17","PARTN":"1","PARTNAME":"Microsoft reserved partition","SEQNUM":"4405","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2279253639"} Aug 31 15:50:47 volumio volumio[11942]: error: Cannot associate FS Label, not mounting Aug 31 15:50:47 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 15:50:47 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 15:50:47 volumio kernel: usb 1-1.1: new high-speed USB device number 58 using xhci_hcd Aug 31 15:50:47 volumio kernel: usb 1-1.1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 2.15 Aug 31 15:50:47 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 31 15:50:47 volumio kernel: usb 1-1.1: Product: MING Aug 31 15:50:47 volumio kernel: usb 1-1.1: Manufacturer: JMicron Aug 31 15:50:47 volumio kernel: usb 1-1.1: SerialNumber: 7401000101F0 Aug 31 15:50:47 volumio kernel: scsi host1: uas Aug 31 15:50:47 volumio kernel: scsi 1:0:0:0: Direct-Access WDC WD40 EZRZ-00GXCB0 0215 PQ: 0 ANSI: 6 Aug 31 15:50:47 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Aug 31 15:50:47 volumio kernel: sd 1:0:0:0: [sdb] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB) Aug 31 15:50:47 volumio kernel: sd 1:0:0:0: [sdb] 4096-byte physical blocks Aug 31 15:50:47 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Aug 31 15:50:47 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 5f 00 00 08 Aug 31 15:50:47 volumio kernel: sd 1:0:0:0: [sdb] Disabling FUA Aug 31 15:50:47 volumio kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Aug 31 15:50:47 volumio kernel: sd 1:0:0:0: [sdb] Preferred minimum I/O size 4096 bytes Aug 31 15:50:47 volumio kernel: sd 1:0:0:0: [sdb] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Aug 31 15:50:47 volumio kernel: sdb: sdb1 sdb2 Aug 31 15:50:47 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI disk Aug 31 15:50:47 volumio volumio[11942]: error: Cannot associate FS Label, not mounting Aug 31 15:50:47 volumio volumio[11942]: info: Mounting Device BLANK-4TB Aug 31 15:50:47 volumio volumio[11942]: /bin/mkdir: cannot create directory ‘/mnt/USB/BLANK-4TB’: File exists Aug 31 15:50:47 volumio volumio[11942]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/BLANK-4TB" Aug 31 15:50:47 volumio volumio[11942]: /bin/mkdir: cannot create directory ‘/mnt/USB/BLANK-4TB’: File exists Aug 31 15:50:47 volumio sudo[12981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/BLANK-4TB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 31 15:50:47 volumio sudo[12981]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 15:50:50 volumio fsct-plugin[11942]: Failed to initialize device 152d:0578: IO error -> Permission denied (os error 13) Aug 31 15:50:52 volumio sudo[12981]: pam_unix(sudo:session): session closed for user root Aug 31 15:50:52 volumio volumio[11942]: ntfs-3g-mount: failed to access mountpoint /media/BLANK-4TB: Transport endpoint is not connected Aug 31 15:50:52 volumio volumio[11942]: error: Failed to mount BLANK-4TB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb2" "/mnt/USB/BLANK-4TB" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 31 15:50:52 volumio volumio[11942]: ntfs-3g-mount: failed to access mountpoint /media/BLANK-4TB: Transport endpoint is not connected Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::sendMpdCommand update Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::sendMpdCommand update Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:50:52 volumio volumio[11942]: info: Aug 31 15:50:52 volumio volumio[11942]: ---------------------------- MPD announces state update: update Aug 31 15:50:52 volumio volumio[11942]: info: sendMpdCommand update took 9 milliseconds Aug 31 15:50:52 volumio volumio[11942]: info: ControllerMpd::getState Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:50:52 volumio volumio[11942]: info: sendMpdCommand update took 9 milliseconds Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:50:52 volumio volumio[11942]: info: Aug 31 15:50:52 volumio volumio[11942]: ---------------------------- MPD announces state update: update Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:50:52 volumio volumio[11942]: info: Aug 31 15:50:52 volumio volumio[11942]: ---------------------------- MPD announces state update: update Aug 31 15:50:52 volumio volumio[11942]: info: ControllerMpd::getState Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:50:52 volumio volumio[11942]: info: ControllerMpd::getState Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:50:52 volumio volumio[11942]: info: sendMpdCommand status took 8 milliseconds Aug 31 15:50:52 volumio volumio[11942]: info: sendMpdCommand status took 5 milliseconds Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::parseState Aug 31 15:50:52 volumio volumio[11942]: info: Command Router : Notfying DB Updatefalse Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::Close All Modals sent Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::parseState Aug 31 15:50:52 volumio volumio[11942]: info: ControllerMpd::pushState Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::servicePushState Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::pushState Aug 31 15:50:52 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::volumioPushState Aug 31 15:50:52 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:52 volumio volumio[11942]: 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} Aug 31 15:50:52 volumio volumio[11942]: verbose: CURRENT POSITION 0 Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::syncState stateService stop Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::syncState currentStatus stop Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::pushState Aug 31 15:50:52 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::volumioPushState Aug 31 15:50:52 volumio volumio[11942]: info: No code Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::pushState Aug 31 15:50:52 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::volumioPushState Aug 31 15:50:52 volumio volumio[11942]: info: ------------------------------ 322ms Aug 31 15:50:52 volumio volumio[11942]: info: sendMpdCommand status took 318 milliseconds Aug 31 15:50:52 volumio volumio[11942]: info: sendMpdCommand status took 318 milliseconds Aug 31 15:50:52 volumio volumio[11942]: info: sendMpdCommand status took 317 milliseconds Aug 31 15:50:52 volumio volumio[11942]: info: sendMpdCommand status took 317 milliseconds Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::parseState Aug 31 15:50:52 volumio volumio[11942]: info: Command Router : Notfying DB Updatefalse Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::Close All Modals sent Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::parseState Aug 31 15:50:52 volumio volumio[11942]: info: Command Router : Notfying DB Updatefalse Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::Close All Modals sent Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::parseState Aug 31 15:50:52 volumio volumio[11942]: verbose: ControllerMpd::parseState Aug 31 15:50:52 volumio volumio[11942]: info: ControllerMpd::pushState Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::servicePushState Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::pushState Aug 31 15:50:52 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::volumioPushState Aug 31 15:50:52 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:52 volumio volumio[11942]: 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} Aug 31 15:50:52 volumio volumio[11942]: verbose: CURRENT POSITION 0 Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::syncState stateService stop Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::syncState currentStatus stop Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::pushState Aug 31 15:50:52 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::volumioPushState Aug 31 15:50:52 volumio volumio[11942]: info: No code Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::pushState Aug 31 15:50:52 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::volumioPushState Aug 31 15:50:52 volumio volumio[11942]: info: ControllerMpd::pushState Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::servicePushState Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::pushState Aug 31 15:50:52 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::volumioPushState Aug 31 15:50:52 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:52 volumio volumio[11942]: 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} Aug 31 15:50:52 volumio volumio[11942]: verbose: CURRENT POSITION 0 Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::syncState stateService stop Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::syncState currentStatus stop Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::pushState Aug 31 15:50:52 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::volumioPushState Aug 31 15:50:52 volumio volumio[11942]: info: No code Aug 31 15:50:52 volumio volumio[11942]: info: CoreStateMachine::pushState Aug 31 15:50:52 volumio volumio[11942]: info: CorePlayQueue::getTrack 0 Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::volumioPushState Aug 31 15:50:52 volumio volumio[11942]: info: ------------------------------ 399ms Aug 31 15:50:52 volumio volumio[11942]: info: ------------------------------ 399ms Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 31 15:50:52 volumio volumio[11942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 31 15:50:52 volumio volumio[11942]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 15:50:52 volumio volumio[11942]: Error: getaddrinfo -3007 Aug 31 15:50:52 volumio volumio[11942]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Aug 31 15:50:52 volumio volumio[11942]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Aug 31 15:50:52 volumio volumio[11942]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Aug 31 15:50:52 volumio volumio[11942]: code: -3007, Aug 31 15:50:52 volumio volumio[11942]: errno: -3007, Aug 31 15:50:52 volumio volumio[11942]: syscall: 'getaddrinfo' Aug 31 15:50:52 volumio volumio[11942]: } Aug 31 15:50:52 volumio volumio[11942]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 15:50:53 volumio sudo[13007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-31 15:49 Aug 31 15:50:53 volumio sudo[13007]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"