-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Wed 2024-04-24 04:11:22 UTC. -- Apr 24 04:10:01 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 24 04:10:01 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 24 04:10:01 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:01 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:01 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:03 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 24 04:10:03 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 24 04:10:03 blueplayer systemd[1]: soundcheck.service: Service RestartSec=15s expired, scheduling restart. Apr 24 04:10:03 blueplayer systemd[1]: soundcheck.service: Scheduled restart job, restart counter is at 394. Apr 24 04:10:03 blueplayer systemd[1]: Stopped Sound check for Addon. Apr 24 04:10:03 blueplayer systemd[1]: Started Sound check for Addon. Apr 24 04:10:04 blueplayer bash[8187]: end Apr 24 04:10:04 blueplayer bash[8187]: endplay Apr 24 04:10:04 blueplayer systemd[1]: soundcheck.service: Succeeded. Apr 24 04:10:04 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 24 04:10:04 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 24 04:10:05 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 24 04:10:05 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 24 04:10:05 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:05 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:05 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:09 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:09 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:09 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:13 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:13 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:13 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:17 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:17 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:17 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:19 blueplayer systemd[1]: soundcheck.service: Service RestartSec=15s expired, scheduling restart. Apr 24 04:10:19 blueplayer systemd[1]: soundcheck.service: Scheduled restart job, restart counter is at 395. Apr 24 04:10:19 blueplayer systemd[1]: Stopped Sound check for Addon. Apr 24 04:10:19 blueplayer systemd[1]: Started Sound check for Addon. Apr 24 04:10:19 blueplayer bash[8200]: end Apr 24 04:10:19 blueplayer bash[8200]: endplay Apr 24 04:10:19 blueplayer systemd[1]: soundcheck.service: Succeeded. Apr 24 04:10:21 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:21 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:21 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:22 blueplayer ntpd[739]: Soliciting pool server 2001:253:207::1:123 Apr 24 04:10:25 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:25 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:25 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:29 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:29 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:29 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:33 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:33 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:33 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:34 blueplayer systemd[1]: soundcheck.service: Service RestartSec=15s expired, scheduling restart. Apr 24 04:10:34 blueplayer systemd[1]: soundcheck.service: Scheduled restart job, restart counter is at 396. Apr 24 04:10:34 blueplayer systemd[1]: Stopped Sound check for Addon. Apr 24 04:10:34 blueplayer systemd[1]: Started Sound check for Addon. Apr 24 04:10:34 blueplayer bash[8209]: end Apr 24 04:10:34 blueplayer bash[8209]: endplay Apr 24 04:10:34 blueplayer systemd[1]: soundcheck.service: Succeeded. Apr 24 04:10:37 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:37 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:37 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:41 blueplayer kernel: usb 1-1.3: new high-speed USB device number 12 using xhci_hcd Apr 24 04:10:41 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:41 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:41 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:41 blueplayer kernel: usb 1-1.3: New USB device found, idVendor=0bc2, idProduct=a003, bcdDevice= 4.19 Apr 24 04:10:41 blueplayer kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 24 04:10:41 blueplayer kernel: usb 1-1.3: Product: Backup+ BK Apr 24 04:10:41 blueplayer kernel: usb 1-1.3: Manufacturer: Seagate Apr 24 04:10:41 blueplayer kernel: usb 1-1.3: SerialNumber: NA5BMXZP Apr 24 04:10:41 blueplayer kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Apr 24 04:10:41 blueplayer kernel: scsi host0: usb-storage 1-1.3:1.0 Apr 24 04:10:42 blueplayer kernel: scsi 0:0:0:0: Direct-Access Seagate Backup+ BK 0419 PQ: 0 ANSI: 6 Apr 24 04:10:42 blueplayer kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Apr 24 04:10:44 blueplayer kernel: usb usb2-port1: over-current change #11 Apr 24 04:10:44 blueplayer kernel: usb 2-1: USB disconnect, device number 4 Apr 24 04:10:44 blueplayer kernel: sd 2:0:0:0: [sdc] tag#13 uas_zap_pending 0 uas-tag 1 inflight: CMD Apr 24 04:10:44 blueplayer kernel: sd 2:0:0:0: [sdc] tag#13 CDB: opcode=0x28 28 00 1e 66 35 a6 00 00 07 00 Apr 24 04:10:44 blueplayer kernel: sd 2:0:0:0: [sdc] tag#13 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=0x00 cmd_age=2s Apr 24 04:10:44 blueplayer kernel: sd 2:0:0:0: [sdc] tag#13 CDB: opcode=0x28 28 00 1e 66 35 a6 00 00 07 00 Apr 24 04:10:44 blueplayer kernel: blk_update_request: I/O error, dev sdc, sector 510014886 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 0 Apr 24 04:10:44 blueplayer kernel: blk_update_request: I/O error, dev sdc, sector 510014886 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Apr 24 04:10:44 blueplayer kernel: blk_update_request: I/O error, dev sdc, sector 510014886 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Apr 24 04:10:44 blueplayer kernel: blk_update_request: I/O error, dev sdc, sector 510014886 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Apr 24 04:10:44 blueplayer kernel: blk_update_request: I/O error, dev sdc, sector 510014886 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Apr 24 04:10:44 blueplayer kernel: blk_update_request: I/O error, dev sdc, sector 510014982 op 0x0:(READ) flags 0x80700 phys_seg 4 prio class 0 Apr 24 04:10:44 blueplayer kernel: blk_update_request: I/O error, dev sdc, sector 510014982 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Apr 24 04:10:44 blueplayer kernel: blk_update_request: I/O error, dev sdc, sector 510014982 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Apr 24 04:10:44 blueplayer kernel: blk_update_request: I/O error, dev sdc, sector 510014982 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Apr 24 04:10:44 blueplayer kernel: blk_update_request: I/O error, dev sdc, sector 510014982 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 31186) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 31298) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 31452) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 31522) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 31581) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 31666) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 31766) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 31946) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 32023) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 32088) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 32130) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 33560) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 34311) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 34522) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 35551) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 35985) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 37065) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 38277) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 42634) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 43660) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 44078) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 44611) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 45087) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 45269) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 45682) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 47346) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 47927) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 48020) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 48127) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 48468) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 48542) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 49202) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 49412) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 49889) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): Directory bread(block 401301766) failed Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): Directory bread(block 401301767) failed Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): Directory bread(block 401301768) failed Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): Directory bread(block 401301769) failed Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): Directory bread(block 401301770) failed Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): Directory bread(block 401301771) failed Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): Directory bread(block 401301772) failed Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): Directory bread(block 401301773) failed Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): Directory bread(block 401301774) failed Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): Directory bread(block 401301775) failed Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): error, corrupted directory (invalid entries) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): Filesystem has been set read-only Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 116133) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 68865) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 70687) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 82020) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 86687) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 88814) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 93243) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 97636) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 98582) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 100320) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 100588) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 103169) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 105376) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 107288) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 111026) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 112327) Apr 24 04:10:44 blueplayer kernel: FAT-fs (sdc1): FAT read failed (blocknr 33413) Apr 24 04:10:44 blueplayer volumio[1158]: 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/host2/target2:0:0/2:0:0:0/block/sdc/sdc1","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/e5e27329-245c-f036-61e1-d9be17a22f2c /dev/disk/by-uuid/16FC-376A /dev/disk/by-id/ata-WDC_WD10SPZX-75Z10T3_WXF2A51A6YRJ-part1 /dev/disk/by-label/1TB /dev/disk/by-id/wwn-0x50014ee269a79407-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sdc1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host2/target2:0:0/2:0:0:0/block/sdc/sdc1","DEVTYPE":"partition","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_APM":"1","ID_ATA_FEATURE_SET_APM_CURRENT_VALUE":"128","ID_ATA_FEATURE_SET_APM_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":"190","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"190","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":"1TB","ID_FS_LABEL_ENC":"1TB","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"16FC-376A","ID_FS_UUID_ENC":"16FC-376A","ID_FS_VERSION":"FAT32","ID_MODEL":"WDC_WD10SPZX-75Z10T3","ID_MODEL_ENC":"WDC\\x20WD10SPZX-75Z10T3\\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:32","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"1953521664","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"e5e27329-245c-f036-61e1-d9be17a22f2c","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"ea688c13-c552-fb1b-e4fd-e489bb315e15","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":"14244514","ID_SERIAL":"WDC_WD10SPZX-75Z10T3_WXF2A51A6YRJ","ID_SERIAL_SHORT":"WXF2A51A6YRJ","ID_TYPE":"disk","ID_WWN":"0x50014ee269a79407","ID_WWN_WITH_EXTENSION":"0x50014ee269a79407","MAJOR":"8","MINOR":"33","PARTN":"1","SEQNUM":"2009","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"5984088268"} Apr 24 04:10:44 blueplayer kernel: sd 2:0:0:0: [sdc] Synchronizing SCSI cache Apr 24 04:10:44 blueplayer sudo[8222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdc1 Apr 24 04:10:44 blueplayer sudo[8222]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 04:10:44 blueplayer kernel: Buffer I/O error on dev sdc1, logical block 24510, lost async page write Apr 24 04:10:44 blueplayer kernel: Buffer I/O error on dev sdc1, logical block 143729, lost async page write Apr 24 04:10:44 blueplayer sudo[8222]: pam_unix(sudo:session): session closed for user root Apr 24 04:10:45 blueplayer kernel: sd 2:0:0:0: [sdc] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=0x00 Apr 24 04:10:45 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:45 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:45 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:45 blueplayer kernel: usb 2-1: new SuperSpeed Gen 1 USB device number 5 using xhci_hcd Apr 24 04:10:45 blueplayer kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice=25.01 Apr 24 04:10:45 blueplayer kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 24 04:10:45 blueplayer kernel: usb 2-1: Product: External HDD Apr 24 04:10:45 blueplayer kernel: usb 2-1: Manufacturer: JMicron Apr 24 04:10:45 blueplayer kernel: usb 2-1: SerialNumber: 0000007788E1 Apr 24 04:10:45 blueplayer kernel: scsi host2: uas Apr 24 04:10:45 blueplayer kernel: usb usb2-port2: over-current change #8 Apr 24 04:10:45 blueplayer kernel: scsi 2:0:0:0: Direct-Access WDC WD10 SPZX-75Z10T3 2501 PQ: 0 ANSI: 6 Apr 24 04:10:45 blueplayer kernel: sd 2:0:0:0: Attached scsi generic sg1 type 0 Apr 24 04:10:45 blueplayer kernel: sd 2:0:0:0: [sdc] Spinning up disk... Apr 24 04:10:46 blueplayer kernel: usb usb2-port3: over-current change #8 Apr 24 04:10:46 blueplayer kernel: usb usb2-port4: over-current change #7 Apr 24 04:10:46 blueplayer kernel: usb usb2-port1: over-current change #12 Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: MPD Database updated - AlbumList cache refreshed Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: database Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: Apr 24 04:10:48 blueplayer volumio[1158]: ---------------------------- MPD announces state update: update Apr 24 04:10:48 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:48 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: ControllerMpd::getState Apr 24 04:10:48 blueplayer volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Apr 24 04:10:48 blueplayer volumio[1158]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/1TB Apr 24 04:10:49 blueplayer systemd[1]: soundcheck.service: Service RestartSec=15s expired, scheduling restart. Apr 24 04:10:49 blueplayer systemd[1]: soundcheck.service: Scheduled restart job, restart counter is at 397. Apr 24 04:10:49 blueplayer systemd[1]: Stopped Sound check for Addon. Apr 24 04:10:49 blueplayer systemd[1]: Started Sound check for Addon. Apr 24 04:10:49 blueplayer bash[8227]: end Apr 24 04:10:49 blueplayer bash[8227]: endplay Apr 24 04:10:49 blueplayer systemd[1]: soundcheck.service: Succeeded. Apr 24 04:10:49 blueplayer volumio[1158]: mpd error: Connection closed by the server Apr 24 04:10:49 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:49 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:49 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:50 blueplayer kernel: ....ready Apr 24 04:10:50 blueplayer kernel: sd 2:0:0:0: [sdc] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) Apr 24 04:10:50 blueplayer kernel: sd 2:0:0:0: [sdc] 4096-byte physical blocks Apr 24 04:10:50 blueplayer kernel: sd 2:0:0:0: [sdc] Write Protect is off Apr 24 04:10:50 blueplayer kernel: sd 2:0:0:0: [sdc] Mode Sense: 53 00 00 08 Apr 24 04:10:50 blueplayer kernel: sd 2:0:0:0: [sdc] Disabling FUA Apr 24 04:10:50 blueplayer kernel: sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Apr 24 04:10:50 blueplayer kernel: sd 2:0:0:0: [sdc] Optimal transfer size 33553920 bytes not a multiple of physical block size (4096 bytes) Apr 24 04:10:50 blueplayer kernel: sdc: sdc1 Apr 24 04:10:50 blueplayer kernel: sd 2:0:0:0: [sdc] Attached SCSI disk Apr 24 04:10:50 blueplayer volumio[1158]: info: Mounting Device 1TB Apr 24 04:10:50 blueplayer sudo[8246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/1TB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 24 04:10:50 blueplayer sudo[8246]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 04:10:50 blueplayer kernel: FAT-fs (sdc1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Apr 24 04:10:50 blueplayer kernel: FAT-fs (sdc1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Apr 24 04:10:50 blueplayer sudo[8246]: pam_unix(sudo:session): session closed for user root Apr 24 04:10:50 blueplayer volumio[1158]: mpd error: Connection closed by the server Apr 24 04:10:53 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:53 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:53 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:10:57 blueplayer volumio[1158]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 6 Apr 24 04:10:57 blueplayer volumio[1158]: info: CoreCommandRouter::volumioGetState Apr 24 04:10:57 blueplayer volumio[1158]: info: CorePlayQueue::getTrack 4 Apr 24 04:10:57 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:10:57 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:10:57 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:11:01 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:11:01 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:11:01 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:11:03 blueplayer kernel: sd 0:0:0:0: [sda] Spinning up disk... Apr 24 04:11:04 blueplayer systemd[1]: soundcheck.service: Service RestartSec=15s expired, scheduling restart. Apr 24 04:11:04 blueplayer systemd[1]: soundcheck.service: Scheduled restart job, restart counter is at 398. Apr 24 04:11:04 blueplayer systemd[1]: Stopped Sound check for Addon. Apr 24 04:11:04 blueplayer systemd[1]: Started Sound check for Addon. Apr 24 04:11:05 blueplayer bash[8275]: end Apr 24 04:11:05 blueplayer bash[8275]: endplay Apr 24 04:11:05 blueplayer systemd[1]: soundcheck.service: Succeeded. Apr 24 04:11:05 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:11:05 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:11:05 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:11:08 blueplayer kernel: usb 1-1.3: USB disconnect, device number 12 Apr 24 04:11:09 blueplayer kernel: .ready Apr 24 04:11:09 blueplayer kernel: sd 0:0:0:0: [sda] Read Capacity(10) failed: Result: hostbyte=0x07 driverbyte=0x00 Apr 24 04:11:09 blueplayer kernel: sd 0:0:0:0: [sda] Sense not available. Apr 24 04:11:09 blueplayer kernel: sd 0:0:0:0: [sda] 0 512-byte logical blocks: (0 B/0 B) Apr 24 04:11:09 blueplayer kernel: sd 0:0:0:0: [sda] 0-byte physical blocks Apr 24 04:11:09 blueplayer kernel: sd 0:0:0:0: [sda] Write Protect is on Apr 24 04:11:09 blueplayer kernel: sd 0:0:0:0: [sda] Mode Sense: c0 d6 d8 c6 Apr 24 04:11:09 blueplayer kernel: sd 0:0:0:0: [sda] No Caching mode page found Apr 24 04:11:09 blueplayer kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Apr 24 04:11:09 blueplayer kernel: sd 0:0:0:0: [sda] Read Capacity(10) failed: Result: hostbyte=0x07 driverbyte=0x00 Apr 24 04:11:09 blueplayer kernel: sd 0:0:0:0: [sda] Sense not available. Apr 24 04:11:09 blueplayer kernel: sd 0:0:0:0: [sda] Attached SCSI disk Apr 24 04:11:09 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:11:09 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:11:09 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:11:13 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:11:13 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:11:13 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:11:17 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:11:17 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:11:17 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:11:20 blueplayer systemd[1]: soundcheck.service: Service RestartSec=15s expired, scheduling restart. Apr 24 04:11:20 blueplayer systemd[1]: soundcheck.service: Scheduled restart job, restart counter is at 399. Apr 24 04:11:20 blueplayer systemd[1]: Stopped Sound check for Addon. Apr 24 04:11:20 blueplayer systemd[1]: Started Sound check for Addon. Apr 24 04:11:20 blueplayer bash[8286]: end Apr 24 04:11:20 blueplayer bash[8286]: endplay Apr 24 04:11:20 blueplayer systemd[1]: soundcheck.service: Succeeded. Apr 24 04:11:21 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 04:11:21 blueplayer volumio[1158]: error: MPD error: The expression evaluated to a falsy value: Apr 24 04:11:21 blueplayer volumio[1158]: assert.ok(self.idling) Apr 24 04:11:22 blueplayer volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 24 04:11:22 blueplayer sudo[8292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /data/albumart/folder/mnt/USB Apr 24 04:11:22 blueplayer sudo[8292]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 04:11:22 blueplayer sudo[8292]: pam_unix(sudo:session): session closed for user root Apr 24 04:11:22 blueplayer volumio[1158]: mpd error: Connection closed by the server Apr 24 04:11:22 blueplayer volumio[1158]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 04:11:22 blueplayer volumio[1158]: Error: Command failed: /usr/bin/mpc update USB Apr 24 04:11:22 blueplayer volumio[1158]: mpd error: Connection closed by the server Apr 24 04:11:22 blueplayer volumio[1158]: at checkExecSyncError (child_process.js:616:11) Apr 24 04:11:22 blueplayer volumio[1158]: at execSync (child_process.js:652:15) Apr 24 04:11:22 blueplayer volumio[1158]: at /volumio/app/plugins/music_service/mpd/index.js:1855:5 Apr 24 04:11:22 blueplayer volumio[1158]: at ChildProcess.exithandler (child_process.js:299:7) Apr 24 04:11:22 blueplayer volumio[1158]: at ChildProcess.emit (events.js:315:20) Apr 24 04:11:22 blueplayer volumio[1158]: at maybeClose (internal/child_process.js:1048:16) Apr 24 04:11:22 blueplayer volumio[1158]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) { Apr 24 04:11:22 blueplayer volumio[1158]: status: 1, Apr 24 04:11:22 blueplayer volumio[1158]: signal: null, Apr 24 04:11:22 blueplayer volumio[1158]: output: [ null, '', 'mpd error: Connection closed by the server\n' ], Apr 24 04:11:22 blueplayer volumio[1158]: pid: 8294, Apr 24 04:11:22 blueplayer volumio[1158]: stdout: '', Apr 24 04:11:22 blueplayer volumio[1158]: stderr: 'mpd error: Connection closed by the server\n' Apr 24 04:11:22 blueplayer volumio[1158]: } Apr 24 04:11:22 blueplayer volumio[1158]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 04:11:22 blueplayer sudo[8305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-24 04:10 Apr 24 04:11:22 blueplayer sudo[8305]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"