Access slow from my Mac

[AndyNJ]

Access to my HDA has been excruciatingly slow lately from the two Macs on my home network. Access from Windows machines (to the HDA) is fine and access from the Macs to the Windows machines and each other is normal. The only problem is the Macs to the HDA and it's only when trying to access files (dashboard is fine). Opening folders takes insanely long amounts of time (like 5 minutes) and transferring files in either direction is just as slow.

Where do I start to troubleshoot this? I can't even think of what would cause this.

[radioz]

Strange.

I would start by looking in some of the log files for information.
Take a look at /var/log/messages and /var/log/greyhole.log.

Post anything that looks suspicious.

[AndyNJ]

I see a lot of stuff like the below in messages. Looks like a hardware issue? Doesn't make sense why only Macs would be affected though, does it?

Code: Select all

Jul 17 03:31:15 donbot rsyslogd: [origin software="rsyslogd" swVersion="4.6.3" x-pid="1545" x-info="http://www.rsyslog.com"] rsyslogd was HUPed, type 'lightweight'. Jul 17 03:31:16 donbot kernel: [368124.286883] ata9.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 Jul 17 03:31:16 donbot kernel: [368124.286897] ata9.00: failed command: READ SECTOR(S) Jul 17 03:31:16 donbot kernel: [368124.286914] ata9.00: cmd 20/00:08:1f:d1:68/00:00:00:00:00/e0 tag 0 pio 4096 in Jul 17 03:31:16 donbot kernel: [368124.286918] res 51/40:07:20:d1:68/0c:01:00:00:00/e0 Emask 0x9 (media error) Jul 17 03:31:16 donbot kernel: [368124.286927] ata9.00: status: { DRDY ERR } Jul 17 03:31:16 donbot kernel: [368124.286932] ata9.00: error: { UNC } Jul 17 03:31:16 donbot kernel: [368124.328779] ata9.00: configured for PIO4 Jul 17 03:31:16 donbot kernel: [368124.328795] ata9: EH complete Jul 17 03:31:19 donbot kernel: [368126.964546] ata9.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 Jul 17 03:31:19 donbot kernel: [368126.964560] ata9.00: failed command: READ SECTOR(S) Jul 17 03:31:19 donbot kernel: [368126.964578] ata9.00: cmd 20/00:08:1f:d1:68/00:00:00:00:00/e0 tag 0 pio 4096 in Jul 17 03:31:19 donbot kernel: [368126.964582] res 51/40:07:20:d1:68/0c:01:00:00:00/e0 Emask 0x9 (media error) Jul 17 03:31:19 donbot kernel: [368126.964590] ata9.00: status: { DRDY ERR } Jul 17 03:31:19 donbot kernel: [368126.964596] ata9.00: error: { UNC } Jul 17 03:31:19 donbot ntfs-3g[1139]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Jul 17 03:31:19 donbot ntfs-3g[1139]: Failed to read of MFT, mft=379660 count=1 br=-1: Input/output error Jul 17 03:31:19 donbot kernel: [368127.004520] ata9.00: configured for PIO4 Jul 17 03:31:19 donbot kernel: [368127.004551] sd 8:0:0:0: [sdi] Unhandled sense code Jul 17 03:31:19 donbot kernel: [368127.004558] sd 8:0:0:0: [sdi] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE Jul 17 03:31:19 donbot kernel: [368127.004567] sd 8:0:0:0: [sdi] Sense Key : Medium Error [current] [descriptor] Jul 17 03:31:19 donbot kernel: [368127.004578] Descriptor sense data with sense descriptors (in hex): Jul 17 03:31:19 donbot kernel: [368127.004583] 72 03 11 04 00 00 00 0c 00 0a 80 00 00 00 00 00 Jul 17 03:31:19 donbot kernel: [368127.004603] 00 68 d1 20 Jul 17 03:31:19 donbot kernel: [368127.004611] sd 8:0:0:0: [sdi] Add. Sense: Unrecovered read error - auto reallocate failed Jul 17 03:31:19 donbot kernel: [368127.004623] sd 8:0:0:0: [sdi] CDB: Read(10): 28 00 00 68 d1 1f 00 00 08 00 Jul 17 03:31:19 donbot kernel: [368127.004642] end_request: I/O error, dev sdi, sector 6869280 Jul 17 03:31:19 donbot kernel: [368127.004653] Buffer I/O error on device sdi1, logical block 858652 Jul 17 03:31:19 donbot kernel: [368127.004705] ata9: EH complete Jul 17 03:31:22 donbot kernel: [368130.197747] ata9.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 Jul 17 03:31:22 donbot kernel: [368130.197761] ata9.00: failed command: READ SECTOR(S) Jul 17 03:31:22 donbot kernel: [368130.197779] ata9.00: cmd 20/00:08:1f:d1:68/00:00:00:00:00/e0 tag 0 pio 4096 in Jul 17 03:31:22 donbot kernel: [368130.197783] res 51/40:07:20:d1:68/0c:01:00:00:00/e0 Emask 0x9 (media error) Jul 17 03:31:22 donbot kernel: [368130.197791] ata9.00: status: { DRDY ERR } Jul 17 03:31:22 donbot kernel: [368130.197797] ata9.00: error: { UNC } Jul 17 03:31:22 donbot kernel: [368130.238701] ata9.00: configured for PIO4 Jul 17 03:31:22 donbot kernel: [368130.238734] ata9: EH complete Jul 17 03:31:25 donbot kernel: [368132.864403] ata9.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 Jul 17 03:31:25 donbot kernel: [368132.864416] ata9.00: failed command: READ SECTOR(S) Jul 17 03:31:25 donbot kernel: [368132.864434] ata9.00: cmd 20/00:08:1f:d1:68/00:00:00:00:00/e0 tag 0 pio 4096 in Jul 17 03:31:25 donbot kernel: [368132.864438] res 51/40:07:20:d1:68/0c:01:00:00:00/e0 Emask 0x9 (media error) Jul 17 03:31:25 donbot kernel: [368132.864446] ata9.00: status: { DRDY ERR } Jul 17 03:31:25 donbot kernel: [368132.864452] ata9.00: error: { UNC } Jul 17 03:31:25 donbot kernel: [368132.904377] ata9.00: configured for PIO4 Jul 17 03:31:25 donbot kernel: [368132.904406] ata9: EH complete Jul 17 03:31:27 donbot kernel: [368135.531032] ata9.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 Jul 17 03:31:27 donbot kernel: [368135.531046] ata9.00: failed command: READ SECTOR(S) Jul 17 03:31:27 donbot kernel: [368135.531063] ata9.00: cmd 20/00:08:1f:d1:68/00:00:00:00:00/e0 tag 0 pio 4096 in Jul 17 03:31:27 donbot kernel: [368135.531067] res 51/40:07:20:d1:68/0c:01:00:00:00/e0 Emask 0x9 (media error) Jul 17 03:31:27 donbot kernel: [368135.531076] ata9.00: status: { DRDY ERR } Jul 17 03:31:27 donbot kernel: [368135.531082] ata9.00: error: { UNC } Jul 17 03:31:28 donbot kernel: [368135.571070] ata9.00: configured for PIO4 Jul 17 03:31:28 donbot kernel: [368135.571110] ata9: EH complete Jul 17 03:31:30 donbot kernel: [368138.197688] ata9.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 Jul 17 03:31:30 donbot kernel: [368138.197702] ata9.00: failed command: READ SECTOR(S) Jul 17 03:31:30 donbot kernel: [368138.197719] ata9.00: cmd 20/00:08:1f:d1:68/00:00:00:00:00/e0 tag 0 pio 4096 in Jul 17 03:31:30 donbot kernel: [368138.197724] res 51/40:07:20:d1:68/0c:01:00:00:00/e0 Emask 0x9 (media error) Jul 17 03:31:30 donbot kernel: [368138.197732] ata9.00: status: { DRDY ERR } Jul 17 03:31:30 donbot kernel: [368138.197738] ata9.00: error: { UNC } Jul 17 03:31:30 donbot kernel: [368138.238635] ata9.00: configured for PIO4 Jul 17 03:31:30 donbot kernel: [368138.238666] ata9: EH complete Jul 17 03:31:33 donbot kernel: [368140.864328] ata9.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 Jul 17 03:31:33 donbot kernel: [368140.864341] ata9.00: failed command: READ SECTOR(S) Jul 17 03:31:33 donbot kernel: [368140.864359] ata9.00: cmd 20/00:08:1f:d1:68/00:00:00:00:00/e0 tag 0 pio 4096 in Jul 17 03:31:33 donbot kernel: [368140.864363] res 51/40:07:20:d1:68/0c:01:00:00:00/e0 Emask 0x9 (media error) Jul 17 03:31:33 donbot kernel: [368140.864371] ata9.00: status: { DRDY ERR } Jul 17 03:31:33 donbot kernel: [368140.864377] ata9.00: error: { UNC } Jul 17 03:31:33 donbot kernel: [368140.905251] ata9.00: configured for PIO4 Jul 17 03:31:33 donbot kernel: [368140.905270] ata9: EH complete Jul 17 03:31:34 donbot kernel: [368141.879325] EXT4-fs error (device sda1): ext4_find_entry: inode #2: (comm gvfsd-trash) reading directory lblock 0 Jul 17 03:31:35 donbot kernel: [368143.530977] ata9.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 Jul 17 03:31:35 donbot kernel: [368143.530991] ata9.00: failed command: READ SECTOR(S) Jul 17 03:31:35 donbot kernel: [368143.531008] ata9.00: cmd 20/00:08:1f:d1:68/00:00:00:00:00/e0 tag 0 pio 4096 in Jul 17 03:31:35 donbot kernel: [368143.531012] res 51/40:07:20:d1:68/0c:01:00:00:00/e0 Emask 0x9 (media error) Jul 17 03:31:35 donbot kernel: [368143.531052] ata9.00: status: { DRDY ERR } Jul 17 03:31:35 donbot kernel: [368143.531058] ata9.00: error: { UNC } Jul 17 03:31:36 donbot kernel: [368143.570945] ata9.00: configured for PIO4 Jul 17 03:31:36 donbot kernel: [368143.570975] sd 8:0:0:0: [sdi] Unhandled sense code Jul 17 03:31:36 donbot kernel: [368143.570981] sd 8:0:0:0: [sdi] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE Jul 17 03:31:36 donbot kernel: [368143.570990] sd 8:0:0:0: [sdi] Sense Key : Medium Error [current] [descriptor] Jul 17 03:31:36 donbot kernel: [368143.571043] Descriptor sense data with sense descriptors (in hex): Jul 17 03:31:36 donbot kernel: [368143.571049] 72 03 11 04 00 00 00 0c 00 0a 80 00 00 00 00 00 Jul 17 03:31:36 donbot kernel: [368143.571101] 00 68 d1 20 Jul 17 03:31:36 donbot kernel: [368143.571121] sd 8:0:0:0: [sdi] Add. Sense: Unrecovered read error - auto reallocate failed Jul 17 03:31:36 donbot kernel: [368143.571134] sd 8:0:0:0: [sdi] CDB: Read(10): 28 00 00 68 d1 1f 00 00 08 00 Jul 17 03:31:36 donbot kernel: [368143.571158] end_request: I/O error, dev sdi, sector 6869280 Jul 17 03:31:36 donbot kernel: [368143.571171] Buffer I/O error on device sdi1, logical block 858652 Jul 17 03:31:36 donbot kernel: [368143.571223] ata9: EH complete Jul 17 03:31:36 donbot ntfs-3g[1139]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Jul 17 03:31:36 donbot ntfs-3g[1139]: Failed to read of MFT, mft=379660 count=1 br=-1: Input/output error Jul 17 03:31:38 donbot kernel: [368146.308692] ata9.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 Jul 17 03:31:38 donbot kernel: [368146.308698] ata9.00: failed command: READ SECTOR(S) Jul 17 03:31:38 donbot kernel: [368146.308703] ata9.00: cmd 20/00:08:1f:d1:68/00:00:00:00:00/e0 tag 0 pio 4096 in Jul 17 03:31:38 donbot kernel: [368146.308704] res 51/40:07:20:d1:68/0c:01:00:00:00/e0 Emask 0x9 (media error) Jul 17 03:31:38 donbot kernel: [368146.308707] ata9.00: status: { DRDY ERR } Jul 17 03:31:38 donbot kernel: [368146.308708] ata9.00: error: { UNC } Jul 17 03:31:38 donbot kernel: [368146.352699] ata9.00: configured for PIO4 Jul 17 03:31:38 donbot kernel: [368146.352735] ata9: EH complete Jul 17 03:31:41 donbot kernel: [368149.008417] ata9.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 Jul 17 03:31:41 donbot kernel: [368149.008431] ata9.00: failed command: READ SECTOR(S) Jul 17 03:31:41 donbot kernel: [368149.008448] ata9.00: cmd 20/00:08:1f:d1:68/00:00:00:00:00/e0 tag 0 pio 4096 in Jul 17 03:31:41 donbot kernel: [368149.008452] res 51/40:07:20:d1:68/0c:01:00:00:00/e0 Emask 0x9 (media error) Jul 17 03:31:41 donbot kernel: [368149.008460] ata9.00: status: { DRDY ERR } Jul 17 03:31:41 donbot kernel: [368149.008466] ata9.00: error: { UNC } Jul 17 03:31:41 donbot kernel: [368149.048368] ata9.00: configured for PIO4

In the greyhole.log, I see some of the below a few times.

Code: Select all

Jul 17 21:37:03 4 initialize: PHP Warning [8]: Undefined offset: 1 in /usr/bin/greyhole-dfree on line 465

Code: Select all

Jul 17 21:37:03 4 write: PHP Warning [2]: file_put_contents(/var/hda/files/drives/drive11/gh/.gh_graveyard/TimeMachine/Bender_**********.sparsebundle/bands/3e4c): failed to open stream: Input/output error in /usr/bin/greyhole on line 2304

However, I'm also seeing a lot of stuff that seems related to drives being full (specifically because of the files in the attic). I ran the empty attic command and there was a ton cleared out so maybe that will help?

[radioz]

If you haven't done so, I recommend going through the Troubleshooting section of the wiki:
http://wiki.amahi.org/index.php/Main_Pa ... leshooting

I'm not familiar with Greyhole, so someone else will need to help with that.

It does look like you are having some disk errors from the log file.

Post the output of 'df -h'

[AndyNJ]

Code: Select all

Filesystem Size Used Avail Use% Mounted on /dev/sde1 29G 13G 15G 47% / tmpfs 1004M 1.2M 1003M 1% /dev/shm /dev/sde2 193G 40G 143G 22% /var/hda/files/drives/drive18 /dev/sdi1 1.9T 1.5T 336G 82% /var/hda/files/drives/drive11 /dev/sdb1 459G 194G 256G 44% /var/hda/files/drives/drive13 /dev/sdd1 1.8T 1.4T 341G 81% /var/hda/files/drives/drive15 /dev/sdf1 1.8T 1.1T 621G 65% /var/hda/files/drives/drive16 /dev/sdg1 459G 180G 270G 41% /var/hda/files/drives/drive17 /dev/sdh1 459G 340G 110G 76% /var/hda/files/drives/drive19 /dev/sdj1 230G 16G 209G 8% /var/hda/files/drives/drive20 //192.168.1.105/CrashPlan1 1.9T 892G 972G 48% /mnt/CrashPlan1 //127.0.0.1/Archive 7.0T 4.9T 2.1T 71% /mnt/samba/Archive //127.0.0.1/Backup 7.0T 4.9T 2.1T 71% /mnt/samba/Backup //127.0.0.1/Books 7.0T 4.9T 2.1T 71% /mnt/samba/Books //127.0.0.1/Docs 7.0T 4.9T 2.1T 71% /mnt/samba/Docs //127.0.0.1/Music 7.0T 4.9T 2.1T 71% /mnt/samba/Music //127.0.0.1/Photos 7.0T 4.9T 2.1T 71% /mnt/samba/Photos //127.0.0.1/Software 7.0T 4.9T 2.1T 71% /mnt/samba/Software //127.0.0.1/TimeMachine 7.0T 4.9T 2.1T 71% /mnt/samba/TimeMachine //127.0.0.1/TimeMachine_DH 7.0T 4.9T 2.1T 71% /mnt/samba/TimeMachine_DH //127.0.0.1/Videos 7.0T 4.9T 2.1T 71% /mnt/samba/Videos //127.0.0.1/VirtualMachines 7.0T 4.9T 2.1T 71% /mnt/samba/VirtualMachines /dev/sda1 230G 43G 182G 20% /var/hda/files/drives/drive12

[AndyNJ]

I just switched the DHCP server back on in Amahi and killed my router's one. That made a HUGE difference.

It's weird though because I've never used Amahi's DHCP server before and this is a new issue.

[AndyNJ]

That didn't last long. It's slow again.