September 23, 201411 yr Author So before replacing the controller, I decided to do a scrub on my btrfs filesystems since I had converted about 90% over to btrfs. Because of the various crashes, almost every one had unrecoverable errors. I tried using btrfs checkw/repair and nothing worked. I started rsyncing the data off back to XFS filesystems, and had about 1-2 files there were not readably that I had lost on each filesystem. During this migration, I replaced the controller with a new Supermicro 8 port. After doing 2 rsync's at once, all the drives on the controller froze and I had to restart the server to get them working again. I restarted my copies with just a single copy, and after a few hours, the entire machine was hung with no messages from a tail of syslog or anything. I did notice that SageTV was running a scan of the media over SMB at them time of the hang. I tried checking the virtual settings in the bios since I had seen that could cause an issue, but my Penguin D didn't have any virtualization settings. I tried booting into the Xen kernel out of nothing else left to try, and I have migrated the last 1.2 TB over night and haven't encountered any issues yet. Not sure if booting into Xen would be any different than having the KVM support. Not too happy with BTRFS, that is the first data that I have lost in several years of running unraid with multiple crashes along they way. May have had corrupted files that I don't know about during crashes, but never had an issue just reading the file and getting IO errors. Guess ill run a parity check and see how it runs. Edit: Strange, under Xen, the parity check is much slower.. It is normally about 99MB/s and under Xen, it is running about 68MB/s Edit: Guess it isn't Xen, rebooted without Xen, and still running slow...
September 24, 201411 yr Author After a few hours of running the parity check without Xen booted, the controller went crazy again. syslog below: https://www.dropbox.com/s/mmrn1ozeaudcwb2/syslog.09232014?dl=0 Booted it back under Xen and kicked off the parity check again, and it is still running, and after getting past the 2TB drives, it is running faster than I have ever seen it run without issue... 148MB/s Is there anything different between booting in Xen as apposed to the regular kernel with KVM? I know it hasn't run long enough to really prove anything concrete.
September 24, 201411 yr You system log is filled with disk errors. Nothing is going to work consistently until you resolve that issue first.
September 25, 201411 yr Author Which errors did you see that looked like a disk issue? RobJ stated he thought it was the controller, which I have replaced as well as all the cabling. It is strange, after booting into Xen yesterday I have run 2 parity checks and done multiple recordings to the server and haven't seen any issues. When i boot with non-Xen unRaid, I couldn't even get a single parity check to run.
September 25, 201411 yr Which errors did you see that looked like a disk issue? RobJ stated he thought it was the controller, which I have replaced as well as all the cabling. Looking at the system log you reference 2 posts up. Did you look at it? It's filled with this kind of stuff: Sep 23 21:42:53 Dumpster kernel: md: disk1 read error, sector=28765168 Sep 23 21:42:53 Dumpster kernel: md: disk0 read error, sector=28765176 Sep 23 21:42:53 Dumpster kernel: md: disk1 read error, sector=28765176 Sep 23 21:42:53 Dumpster kernel: md: disk0 read error, sector=28765184 Sep 23 21:42:53 Dumpster kernel: md: disk1 read error, sector=28765184 Sep 23 21:42:53 Dumpster kernel: md: disk0 read error, sector=28765192 Sep 23 21:42:53 Dumpster kernel: md: disk1 read error, sector=28765192 Sep 23 21:42:53 Dumpster kernel: md: disk0 read error, sector=28765200 Sep 23 21:42:53 Dumpster kernel: md: disk1 read error, sector=28765200 Sep 23 21:42:53 Dumpster kernel: md: disk0 read error, sector=28765208 Sep 23 21:42:53 Dumpster kernel: md: disk1 read error, sector=28765208 Lots of others along same lines. BTW, lots of these messages as well: Sep 23 16:09:51 Dumpster avahi-daemon[2639]: Invalid response packet from host 192.168.0.167. These are meaningless warnings and we added a patch to beta10 to eliminate them. It is strange, after booting into Xen yesterday I have run 2 parity checks and done multiple recordings to the server and haven't seen any issues. When i boot with non-Xen unRaid, I couldn't even get a single parity check to run. Can't explain that. Have you run memory diagnostics?
September 25, 201411 yr Author Those read and write errors seem to always start after all the drives on the controller freeze. Both the old controller and the new are based off Marvell controllers. I thought there was some mention of an issue with Marvell and virtualization in the motherboard, but my mohterboard doesn't seem to have any since it is older. I did track that 167 address down with the avahi errors, and it is a chromecast. I had run a memtest for a few hours before without errors. Probably should have run it longer. Ill keep running various IO test on it under Xen to see if I can get anymore disk/controller errors to happen.
September 25, 201411 yr After a few hours of running the parity check without Xen booted, the controller went crazy again. syslog below: https://www.dropbox.com/s/mmrn1ozeaudcwb2/syslog.09232014?dl=0 Booted it back under Xen and kicked off the parity check again, and it is still running, and after getting past the 2TB drives, it is running faster than I have ever seen it run without issue... 148MB/s Is there anything different between booting in Xen as apposed to the regular kernel with KVM? I know it hasn't run long enough to really prove anything concrete. This was another SAS subsystem failure, almost exactly like MrLondon's situation, see here, here, and here. Everything was running fine, until the following line: Sep 23 21:39:51 Dumpster kernel: sas: Enter sas_scsi_recover_host busy: 194 failed: 194 The SAS module then aborts all 194 tasks, then ALL 8 of your drives are found to be unresponsive, no response to resets or IDENTIFY requests, and quickly disabled. After that, because the higher level UnRAID module is unaware that the drives are effectively disconnected by the lower level kernel module, it continues attempting to read and write to the drives, producing a large number of logged errors. You can try updating the firmware on that SAS card. And MrLondon was able to find some success by moving the Parity drive to a motherboard port. I did some research online, did not find much, but there's a fairly old patch that seems relevant, "ata command timeout oops in mvsas", see first patch here, revised briefer patch here, mentioned in older change log here, other discussion here. Perhaps Tom could verify if patch is currently included? I found one case of an UnRAID user with similar SAS failure, and a suggestion was made to make sure of sufficient power. It seemed a little unfounded at the time, but got me thinking ... All current cases of this involve SAS cards using mvsas, with the card completely loaded with drives, running very hard, typically a parity build or check. Earlier you seemed to think that running with XEN mode was slower but more successful, which is consistent with lower power draw or lower throughput if slower. MrLondon found more success moving parity drive to motherboard, which *might* involve lower power needs. Tom's most recent releases seem to be running a little faster according to some. Perhaps we are pushing the limits of certain hardware, not designed for the throughput we are beginning to see with recent releases, or not designed to handle the increased power demands. I wonder if throttling mechanisms would ensure safe operation in these cases, such as not filling the card with drives, balancing drives across multiple controllers, or a parity throttling feature.
September 25, 201411 yr Author Looks like he is using a similar SAS card that I currently have and having similar issues. The strange thing about the parity check speed is the while it is doing the parity across all 7 drives it is slower than under v5, but once it gets beyond the 2TB drives and only doing 2x4TB data drives, it is running about twice the speed as under v5. Under v5 it would range between 80MB-99MB and now it ranges between 60MB-148MB. Since booting into Xen, I have been pounding the drives with dd's from /dev/zero to a file on each data drive as well as the several parity checks that have been run, and I haven't seen any controller/disk errors. Also, I have another unRaid server running 2 of the same SAS cards w/beta9 and have never seen a crash or error on that server. I think they are all the same firmware version on the SAS cards. There is a bit of a spec difference in power, one is an old Asus MB with Pentium D with 4GB of memory and the other is a newer SuperMicro MB I3 with 8GB. I am wondering if you are right about Xen having something governed, memory or IO that is making it stable.
September 25, 201411 yr Thank you for researching this RobJ, much appreciated... I did some research online, did not find much, but there's a fairly old patch that seems relevant, "ata command timeout oops in mvsas", see first patch here, revised briefer patch here, mentioned in older change log here, other discussion here. Perhaps Tom could verify if patch is currently included? The second patch you cite is indeed what's in the current mv_sas.c file. Yes, do what sureguy suggests... Whether the issue follows the card or not provides a big clue.
September 25, 201411 yr Author Once I finish migrating the drives in the other server I am switching over to XFS I will try switching the cards.. Thanks for the assistance in this problem, I really appreciate everyones help...
September 27, 201411 yr Author Before switching the card I switched back to the non-Xen boot and ran a parity check as a control, and it crashed in the same blazing glory as before with the controller/disks all freezing. Once my parity check completes now under Xen, I will switch the controller and then test a parity check. I am not too confident that it will change anything since this is the second disk controller (totally different manufacturer and non-SAS, but still a Marvel chip) I have tested with the same results.
September 29, 201411 yr Author After switching to beta10, ran one more parity check under non-Xen, and it completed successfully, and then about 2 hours later got a CPU error, and networking stopped working but the disks seemed fine. I manually stopped the array from the cli and then rebooted and it is back up again without a parity check running: Sep 29 04:13:51 Dumpster kernel: ------------[ cut here ]------------ Sep 29 04:13:51 Dumpster kernel: WARNING: CPU: 1 PID: 0 at net/sched/sch_generic.c:264 dev_watchdog+0x197/0x1fd() Sep 29 04:13:51 Dumpster kernel: NETDEV WATCHDOG: eth0 (r8169): transmit queue 0 timed out Sep 29 04:13:51 Dumpster kernel: Modules linked in: md_mod k8temp r8169 mii mvsas libsas scsi_transport_sas ahci libahci pata_amd Sep 29 04:13:51 Dumpster kernel: CPU: 1 PID: 0 Comm: swapper/1 Not tainted 3.16.3-unRAID #3 Sep 29 04:13:51 Dumpster kernel: Hardware name: 113 1/113-M2-E113, BIOS 6.00 PG 09/30/2008 Sep 29 04:13:51 Dumpster kernel: 0000000000000000 ffff88013fc83dd0 ffffffff815d9532 ffff88013fc83e18 Sep 29 04:13:51 Dumpster kernel: ffff88013fc83e08 ffffffff81040687 ffffffff8151cd5e ffff88009a844000 Sep 29 04:13:51 Dumpster kernel: ffff880139ba8000 ffff88009a844360 0000000000000001 ffff88013fc83e68 Sep 29 04:13:51 Dumpster kernel: Call Trace: Sep 29 04:13:51 Dumpster kernel: <IRQ> [<ffffffff815d9532>] dump_stack+0x45/0x56 Sep 29 04:13:51 Dumpster kernel: [<ffffffff81040687>] warn_slowpath_common+0x75/0x8e Sep 29 04:13:51 Dumpster kernel: [<ffffffff8151cd5e>] ? dev_watchdog+0x197/0x1fd Sep 29 04:13:51 Dumpster kernel: [<ffffffff810406e7>] warn_slowpath_fmt+0x47/0x49 Sep 29 04:13:51 Dumpster kernel: [<ffffffff8151cd5e>] dev_watchdog+0x197/0x1fd Sep 29 04:13:51 Dumpster kernel: [<ffffffff8151cbc7>] ? dev_graft_qdisc+0x69/0x69 Sep 29 04:13:51 Dumpster kernel: [<ffffffff8151cbc7>] ? dev_graft_qdisc+0x69/0x69 Sep 29 04:13:51 Dumpster kernel: [<ffffffff81048650>] call_timer_fn.isra.28+0x18/0x70 Sep 29 04:13:51 Dumpster kernel: [<ffffffff8104911e>] run_timer_softirq+0x1f1/0x274 Sep 29 04:13:51 Dumpster kernel: [<ffffffff8104419f>] __do_softirq+0xcd/0x1ce Sep 29 04:13:51 Dumpster kernel: [<ffffffff8104442e>] irq_exit+0x40/0x87 Sep 29 04:13:51 Dumpster kernel: [<ffffffff8100c1c0>] do_IRQ+0xb4/0xcd Sep 29 04:13:51 Dumpster kernel: [<ffffffff815dfeed>] common_interrupt+0x6d/0x6d Sep 29 04:13:51 Dumpster kernel: <EOI> [<ffffffff8106aa65>] ? pick_next_task_fair+0x37e/0x402 Sep 29 04:13:51 Dumpster kernel: [<ffffffff81034cc0>] ? native_safe_halt+0x6/0x8 Sep 29 04:13:51 Dumpster kernel: [<ffffffff810125db>] default_idle+0x9/0xd Sep 29 04:13:51 Dumpster kernel: [<ffffffff810126bd>] amd_e400_idle+0xb4/0xd9 Sep 29 04:13:51 Dumpster kernel: [<ffffffff81012c6d>] arch_cpu_idle+0xa/0xc Sep 29 04:13:51 Dumpster kernel: [<ffffffff8106f316>] cpu_startup_entry+0x12c/0x232 Sep 29 04:13:51 Dumpster kernel: [<ffffffff8102d21f>] start_secondary+0x1bf/0x1c4 Sep 29 04:13:51 Dumpster kernel: ---[ end trace be7e0d861428dbd1 ]--- Sep 29 04:13:51 Dumpster kernel: r8169 0000:05:00.0 eth0: link up Sep 29 04:14:57 Dumpster kernel: r8169 0000:05:00.0 eth0: link up Sep 29 04:19:33 Dumpster kernel: r8169 0000:05:00.0 eth0: link up Sep 29 04:25:39 Dumpster kernel: r8169 0000:05:00.0 eth0: link up Sep 29 04:45:51 Dumpster kernel: r8169 0000:05:00.0 eth0: link up Sep 29 05:12:15 Dumpster kernel: r8169 0000:05:00.0 eth0: link up Complete syslog attached syslog.09292014.txt
September 30, 201411 yr Author Updated to beta10a and started in non-Xen, and kicked off the parity build, and it completed without any issues. Guess I will see how it runs over the next few days.
September 30, 201411 yr I am running a continual tail on the syslog and am getting the following symptoms at the moment using v6b10a and the KVM Boot option. There does appear to be a bit of a pattern. Create a new config. Run for a while in unprotected mode doing some file operations. No error messages Stop the array and assign a (6TB) parity drive Start the array and build parity for the first time. No error messages When parity has been built OK, then run for a while manipulating files on the array. No error messages. Select the option to check parity. I now start getting the CPU-stalled messages at regular intervals. The system seems to continue for a while, but eventually stops responding. This suggest to me that there is something that is specific to the parity checking code that is causing the above issue. I do have a few plugins installed (cache_dirs, ntfs-3g) so I need to check if booting without those makes any difference. to the behaviour. It will take a while to check out as building parity takes quite a while with 6TB parity and a mixture of 2, 3, 4 and 6TB data drives.
September 30, 201411 yr Author I can image a 6TB parity disk probably takes a bit of time... I haven't seen any errors using it over night, but I am kicking off another parity check now to see what happens. My first run was without running any plugins, my current parity check is running with cache_dirs also started. It usually takes about 12 hours, Ill report back what happens.
October 1, 201411 yr I am running a continual tail on the syslog and am getting the following symptoms at the moment using v6b10a and the KVM Boot option. There does appear to be a bit of a pattern. Create a new config. Run for a while in unprotected mode doing some file operations. No error messages Stop the array and assign a (6TB) parity drive Start the array and build parity for the first time. No error messages When parity has been built OK, then run for a while manipulating files on the array. No error messages. Select the option to check parity. I now start getting the CPU-stalled messages at regular intervals. The system seems to continue for a while, but eventually stops responding. This suggest to me that there is something that is specific to the parity checking code that is causing the above issue. I do have a few plugins installed (cache_dirs, ntfs-3g) so I need to check if booting without those makes any difference. to the behaviour. It will take a while to check out as building parity takes quite a while with 6TB parity and a mixture of 2, 3, 4 and 6TB data drives. I have now repeated the sequence of actions mentioned above on a system with no plugins besides those that come as part of unRAID with the same results. I have attached a syslog that shows the errors starts occurring soon after I start the parity check. I have not included the syslog from the period before this when parity was originally created and mover was moving a lot of files to the array, but there were definitely no errors occurring during that period. The fact that I have managed to replicate this sequence of events several times seems more than a co-incidence. It really looks as though there is some code that only gets invoked during parity checking rather than parity creation that can cause these symptoms. I would guess it is somewhere in the 'md' driver as I assume that is responsible for checking/correcting parity? syslog.zip
October 1, 201411 yr Author Strange, mine doesn't get disk errors anymore and doesn't crash the server, but repairs the same parity block each run now. Almost like a non correcting run even though i am specifying to correct parity errors.
October 2, 201411 yr Author Kicked off a 3rd parity check to see what happens, after about 6 hours, the server was hung, blank console and no abnormal messages from the syslog tail... Booting up into Xen and running another parity check to see if it is behaving as it did before being stable under Xen boot.
October 2, 201411 yr I've booted into Zen mode this time and started a parity check. It has not completed yet but so far no errors are being reported, whereas when booted to KVM mode they were occurring for me quite quickly. Looks like it could well be something particular to KVM mode? As I am interested in running VM's with hardware pass-thru for which KVM mode seems to look like the better option I hope the cause can be identified. Still seems a bit stranger that even in KVM mode I was only getting it during parity checking!
October 2, 201411 yr Author Strange, parity check ended without any issues, and had no parity errors to correct... Not sure without Xen why it was continually 1 block, for 2 runs, and then a crash, under Xen works fine...
October 3, 201411 yr I was stopping the array, and on the gui it stopped responding after "Stopping AFP". My ssh sessions was also hung that I had already established. I checked the console, and it was blank and seemed non-responsive. I had to end up power cycling the server to get it to boot. I did see this message on the ssh sessions I had opened when it hung: mdcmd (54): nocheck md: nocheck_array: check not active INFO: rcu_sched self-detected stall on CPU { 0} (t=6001 jiffies g=860756 c=860755 q=436) sending NMI to all CPUs: NMI backtrace for cpu 0 CPU: 0 PID: 4334 Comm: tail Not tainted 3.16.0-unRAID #6 Hardware name: 113 1/113-M2-E113, BIOS 6.00 PG 09/30/2008 task: ffff88013990b720 ti: ffff88007eaf8000 task.ti: ffff88007eaf8000 RIP: 0010:[<ffffffff8102feea>] [<ffffffff8102feea>] arch_trigger_all_cpu_backtrace+0xbf/0xcd RSP: 0018:ffff88013fc03e28 EFLAGS: 00000046 RAX: 0000000000000000 RBX: 0000000000002710 RCX: 0000000000000040 RDX: 0000000000000001 RSI: 0000000000000100 RDI: 0000000000418958 RBP: ffff88013fc03e30 R08: 0000000000000046 R09: 0000000000000000 R10: 0000000000000000 R11: ffffffff818f2ed0 R12: ffff88013fc0e210 R13: 0000000000000000 R14: ffffffff8176b580 R15: 0000000000000000 FS: 00002ab4aa258b80(0000) GS:ffff88013fc00000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b CR2: 00002b009aaff610 CR3: 000000007ebe3000 CR4: 00000000000007f0 Stack: ffffffff8176b580 ffff88013fc03e88 ffffffff8107d15e ffff88013fc0df48 ffffffff817c2750 0000000000000000 00000000000001b4 ffff88013990b720 0000000000000000 0000000000000000 ffffffff81085144 ffff88013fc0df48 Call Trace: <IRQ> [<ffffffff8107d15e>] rcu_check_callbacks+0x1e3/0x503 [<ffffffff81085144>] ? tick_sched_handle+0x34/0x34 [<ffffffff8104941e>] update_process_times+0x38/0x60 [<ffffffff81085142>] tick_sched_handle+0x32/0x34 [<ffffffff81085179>] tick_sched_timer+0x35/0x53 [<ffffffff8105ae2c>] __run_hrtimer.isra.28+0x57/0xb0 [<ffffffff8105b305>] hrtimer_interrupt+0xd9/0x1c0 [<ffffffff8102e7fc>] local_apic_timer_interrupt+0x4f/0x52 [<ffffffff8102ebce>] smp_apic_timer_interrupt+0x3a/0x4b [<ffffffff8155919d>] apic_timer_interrupt+0x6d/0x80 <EOI> [<ffffffff813094ee>] ? vgacon_scroll+0xc2/0x2a6 [<ffffffff8136bc8f>] scrup+0xc5/0xe2 [<ffffffff8136bcd5>] lf+0x29/0x61 [<ffffffff8136e948>] do_con_trol+0x197/0x1404 [<ffffffff8137034e>] do_con_write.part.21+0x799/0x7d5 [<ffffffff81074196>] ? console_unlock+0x318/0x347 [<ffffffff813703d6>] con_write+0x20/0x33 [<ffffffff8135e254>] do_output_char+0x8b/0x1a6 [<ffffffff8135edbd>] n_tty_write+0x30a/0x401 [<ffffffff81061ff5>] ? wake_up_process+0x32/0x32 [<ffffffff8135bf33>] tty_write+0x19b/0x21d [<ffffffff8135eab3>] ? process_echoes+0x69/0x69 [<ffffffff810ef9ad>] vfs_write+0xb5/0x169 [<ffffffff810efeef>] SyS_write+0x42/0x86 [<ffffffff815583a9>] system_call_fastpath+0x16/0x1b Code: 00 bb 10 27 00 00 be 00 01 00 00 48 c7 c7 d0 22 7c 81 e8 56 b7 2a 00 85 c0 74 0b f0 80 25 be 5e 8b 00 fe 5b 5d c3 bf 58 89 41 00 <e8> 27 91 2a 00 ff cb 75 d2 eb e5 c3 66 90 48 63 c7 81 c7 07 02 NMI backtrace for cpu 1 CPU: 1 PID: 4253 Comm: shfs Not tainted 3.16.0-unRAID #6 Hardware name: 113 1/113-M2-E113, BIOS 6.00 PG 09/30/2008 task: ffff8800b1632760 ti: ffff88007e470000 task.ti: ffff88007e470000 RIP: 0033:[<00002ab6d112d1b0>] [<00002ab6d112d1b0>] 0x2ab6d112d1b0 RSP: 002b:00002ab6d235eeb8 EFLAGS: 00000202 RAX: 0000000000000000 RBX: 00002ab6d0f37c48 RCX: 000000000000da48 RDX: 0000000000610158 RSI: 0000000000000000 RDI: 0000000000610158 RBP: 00000000006100f0 R08: 0000000000000000 R09: 000000000000109d R10: 0000000000000008 R11: 0000000000000000 R12: 0000000000610138 R13: 0000000000610158 R14: 00002ab6d235f9c0 R15: 00000000006100f0 FS: 00002ab6d235f700(0000) GS:ffff88013fc80000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00002b35f7f00ac0 CR3: 000000007e255000 CR4: 00000000000007e0 I also see these messages appear when starting a paritycheck in V6Beta9. Paritycheck speed is very "jumpy" when this happens going from 45 to 80 MB/s. When these messages stop Paritycheck speed is back to over 100MB/s after the first 2 TB. I saw these messages show up in V5betas too. Are the a debug thingy?
October 3, 201411 yr I also see these messages appear when starting a paritycheck in V6Beta9. Paritycheck speed is very "jumpy" when this happens going from 45 to 80 MB/s. When these messages stop Paritycheck speed is back to over 100MB/s after the first 2 TB. I saw these messages show up in V5betas too. Are the a debug thingy? Are you booting into KVM or Zen mode? I was getting these regularly in KVM mode, but got much better behaviour in Xen mode. Not sure why KVM and Xen modes should be different. I wonder if it is maybe related to the Kernel compile time options that are chosen when creating the different kernel types. Also do you see this only during parity check or at any other time? I found I could build parity for the first time without getting these error messages. It will be interesting to see if anything different happens when LimeTech give us a kernel without either Xen or KVM support included.
October 3, 201411 yr Author I never had any issues under v5 with the server. It seem to start about the time the new filesystems were introduced. I had a mix of XFS/BTRFS/Reiserfs when it started, and seemed to run better when I migrated over to mostly BTRFS, but then I decided to run a scrub on the filesystems to make sure all was good, and found from the crashes every BTRFS filesystem had several files there were uncorrectable. I migrated everything complete off BTRFS to XFS and with beta10a I wasn't seeing hard crashes, but parity corrections that each time would correct the same blocks and then finally a hard crash. I have since booted back under Xen, and haven't seen a single error message or crash. I am wondering if it is something to do with memory. I have a larger unRaid server with 8GB of memory, and haven't seen a single crash or error and it is using the same IO cards. I am assuming booting under Xen unraid is a domain so I wonder if it is treating memory a bit different. Just grasping as straws...
October 3, 201411 yr I just use normal unraid mode, no xen. But I saw these also in V5betas, in the V5 final they were gone.
Archived
This topic is now archived and is closed to further replies.