From: James Cloos Subject: Re: [Regression] Filesystem I/O is CPU-bound in rc7 and rc8 Date: Fri, 19 Feb 2010 16:37:30 -0500 Message-ID: References: <20100215154516.GB11057@think> <21d7e9971002191302s76111f6fyd986c51b66e214ae@mail.gmail.com> Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Cc: linux-kernel@vger.kernel.org, Chris Mason , linux-fsdevel@vger.kernel.org, linux-ext4@vger.kernel.org, linux-btrfs@vger.kernel.org, jfs-discussion@lists.sourceforge.net To: Dave Airlie Return-path: Received: from eagle.jhcloos.com ([207.210.242.212]:40407 "EHLO eagle.jhcloos.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1752538Ab0BSViM (ORCPT ); Fri, 19 Feb 2010 16:38:12 -0500 In-Reply-To: <21d7e9971002191302s76111f6fyd986c51b66e214ae@mail.gmail.com> (Dave Airlie's message of "Sat, 20 Feb 2010 07:02:23 +1000") Sender: linux-ext4-owner@vger.kernel.org List-ID: >>>>> "Dave" == Dave Airlie writes: Dave> On Thu, Feb 18, 2010 at 6:04 AM, James Cloos wrote: >> While getting re-aquainted with perf, and after upgrading to the current >> version, I see that do_page_fault, unmap_vmas, get_page_from_freelist, >> handle_mm_fault, __do_fault and T.1339 tend to get the most time when the >> kernel is spinning. Dave> We have an AGP related issue with WC/UC paging that might be related, Dave> but I'd need more info. OK. I do use radeon kms (r100). On a lark, I tried out a non-kms boot this morning, but X never started; I was going to update all of the user-mode code (dri2, mesa, x, the ati driver, etc) before tyring again to check whether kms was required to hit the issue. That takes a few hours to do on this box. Dave> dmesg? The only reelvant part of dmesg are of the form: Feb 14 01:37:27 [kernel] [ 3720.582130] INFO: task btrfs-transacti:2527 blocked for more than 120 seconds. Feb 14 01:37:27 [kernel] [ 3720.582140] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Feb 14 01:37:27 [kernel] [ 3720.582149] btrfs-transac D da2ba5b0 0 2527 2 0x00000000 Feb 14 01:37:27 [kernel] [ 3720.582163] da33af60 00000046 c170f240 da2ba5b0 037a730a 00000343 da2ba854 da2ba5b0 Feb 14 01:37:27 [kernel] [ 3720.582181] df9557f4 da2ba5b0 df9557f8 da33af84 c152c406 df9557f8 df9557f8 da2ba5b0 Feb 14 01:37:27 [kernel] [ 3720.582199] ffffffff da212000 da33afa0 da2ba5b0 da33af8c c152bf81 da33afc0 c11b4ecc Feb 14 01:37:27 [kernel] [ 3720.582216] Call Trace: Feb 14 01:37:27 [kernel] [ 3720.582241] [] __mutex_lock_slowpath+0x96/0x120 Feb 14 01:37:27 [kernel] [ 3720.582253] [] mutex_lock+0x11/0x20 Feb 14 01:37:27 [kernel] [ 3720.582269] [] transaction_kthread+0xcc/0x1e0 Feb 14 01:37:27 [kernel] [ 3720.582281] [] ? preempt_schedule+0x33/0x50 Feb 14 01:37:27 [kernel] [ 3720.582292] [] ? transaction_kthread+0x0/0x1e0 Feb 14 01:37:27 [kernel] [ 3720.582306] [] kthread+0x74/0x80 Feb 14 01:37:27 [kernel] [ 3720.582316] [] ? kthread+0x0/0x80 Feb 14 01:37:27 [kernel] [ 3720.582328] [] kernel_thread_helper+0x6/0x10 Dave> lspci -vv? 00:00.0 Host bridge: Intel Corporation 82815 815 Chipset Host Bridge and Memory Controller Hub (rev 04) Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx- Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- SERR- Capabilities: [a0] AGP version 2.0 Status: RQ=32 Iso- ArqSz=0 Cal=0 SBA+ ITACoh- GART64- HTrans- 64bit- FW- AGP3- Rate=x1,x2,x4 Command: RQ=1 ArqSz=0 Cal=0 SBA+ AGP+ GART64- 64bit- FW- Rate=x4 Kernel driver in use: agpgart-intel 00:01.0 PCI bridge: Intel Corporation 82815 815 Chipset AGP Bridge (rev 04) (prog-if 00 [Normal decode]) Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx- Status: Cap- 66MHz+ UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- SERR- TAbort- Reset- FastB2B- PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn- 00:1e.0 PCI bridge: Intel Corporation 82801 Mobile PCI Bridge (rev 03) (prog-if 00 [Normal decode]) Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx- Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- SERR- TAbort- Reset- FastB2B- PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn- 00:1f.0 ISA bridge: Intel Corporation 82801BAM ISA Bridge (LPC) (rev 03) Control: I/O+ Mem+ BusMaster+ SpecCycle+ MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx- Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- SERR- TAbort- SERR- TAbort- SERR- TAbort- SERR- TAbort- SERR- TAbort- SERR- TAbort- Reset- FastB2B- PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn- Capabilities: [80] Power Management version 2 Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=0mA PME(D0-,D1+,D2+,D3hot+,D3cold+) Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME- Bridge: PM- B3+ Capabilities: [90] CompactPCI hot-swap 02:0f.0 CardBus bridge: Texas Instruments PCI4451 PC card Cardbus Controller Subsystem: Dell PCI4451 PC card CardBus Controller (Inspiron 8100) Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx- Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- SERR- Reset+ 16bInt+ PostWrite+ 16-bit legacy interface ports at 0001 Kernel driver in use: yenta_cardbus 02:0f.1 CardBus bridge: Texas Instruments PCI4451 PC card Cardbus Controller Subsystem: Dell PCI4451 PC card CardBus Controller (Inspiron 8100) Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx- Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- SERR- Reset- 16bInt- PostWrite+ 16-bit legacy interface ports at 0001 Kernel driver in use: yenta_cardbus 02:0f.2 FireWire (IEEE 1394): Texas Instruments PCI4451 IEEE-1394 Controller (prog-if 10 [OHCI]) Subsystem: Dell PCI4451 IEEE-1394 Controller (Dell Inspiron 8100) Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx- Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- SERR- TAbort- SERR- TAbort- SERR- TAbort- SERR- TAbort- SERR- TAbort- SERR- TAbort- SERR- OpenPGP: 1024D/ED7DAEA6