lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [<thread-prev] [thread-next>] [day] [month] [year] [list]
Message-ID: <1303926637.2583.17.camel@mulgrave.site>
Date:	Wed, 27 Apr 2011 12:50:37 -0500
From:	James Bottomley <James.Bottomley@...senPartnership.com>
To:	Chris Mason <chris.mason@...cle.com>
Cc:	linux-fsdevel <linux-fsdevel@...r.kernel.org>,
	linux-mm <linux-mm@...ck.org>,
	linux-kernel <linux-kernel@...r.kernel.org>,
	linux-ext4 <linux-ext4@...r.kernel.org>
Subject: Re: [BUG] fatal hang untarring 90GB file, possibly writeback
 related.

On Wed, 2011-04-27 at 13:34 -0400, Chris Mason wrote:
> [ cc'd linux-ext4 ]
> 
> James is hitting softlockups in kswapd while doing writes to a large
> ext4 file.
> 
> Excerpts from James Bottomley's message of 2011-04-27 13:21:42 -0400:
> > On Wed, 2011-04-27 at 12:54 -0400, Chris Mason wrote:
> > > Ok, I'd try turning it on so we catch the sleeping with a spinlock held
> > > case better.
> > 
> > Will do, that's CONFIG_PREEMPT (rather than CONFIG_PREEMPT_VOLUNTARY)?
> > 
> > This is the trace with sysrq-l and sysrq-w
> 
> > 
> > The repro this time doesn't have a soft lockup, just the tar is hung and
> > one of my CPUs is in 99% system.
> > [  454.742935] flush-253:2     D 0000000000000000     0   793      2 0x00000000
> > [  454.745425]  ffff88006355b710 0000000000000046 ffff88006355b6b0 ffffffff00000000
> > [  454.747955]  ffff880037ee9700 ffff88006355bfd8 ffff88006355bfd8 0000000000013b40
> > [  454.750506]  ffffffff81a0b020 ffff880037ee9700 ffff88006355b710 000000018106e7c3
> > [  454.753048] Call Trace:
> > [  454.755537]  [<ffffffff811c82b8>] do_get_write_access+0x1c6/0x38d
> > [  454.758071]  [<ffffffff8106e88b>] ? autoremove_wake_function+0x3d/0x3d
> > [  454.760644]  [<ffffffff811c8588>] jbd2_journal_get_write_access+0x2b/0x42
> > [  454.763206]  [<ffffffff8118ea4f>] ? ext4_read_block_bitmap+0x54/0x2d0
> > [  454.765770]  [<ffffffff811b5888>] __ext4_journal_get_write_access+0x58/0x66
> > [  454.768353]  [<ffffffff811b8dbe>] ext4_mb_mark_diskspace_used+0x70/0x2ae
> > [  454.770942]  [<ffffffff811bb10e>] ext4_mb_new_blocks+0x1c8/0x3c2
> > [  454.773501]  [<ffffffff811b4628>] ext4_ext_map_blocks+0x1961/0x1c04
> > [  454.776082]  [<ffffffff8122ed78>] ? radix_tree_gang_lookup_tag_slot+0x81/0xa2
> > [  454.778711]  [<ffffffff810d55f9>] ? find_get_pages_tag+0x3b/0xd6
> > [  454.781323]  [<ffffffff811967fa>] ext4_map_blocks+0x112/0x1e7
> > [  454.783894]  [<ffffffff811984e8>] mpage_da_map_and_submit+0x93/0x2cd
> > [  454.786491]  [<ffffffff81198de5>] ext4_da_writepages+0x2c1/0x44d
> > [  454.789090]  [<ffffffff810ddeb4>] do_writepages+0x21/0x2a
> 
> So our flusher threads are stuck waiting in
> jbd2_journal_get_write_access, which means they aren't cleaning dirty
> pages.
> 
> In order to get write access, they probably need the transaction to
> commit:
> 
> > [  454.828711] jbd2/dm-2-8     D 0000000000000000     0   799      2 0x00000000
> > [  454.831390]  ffff88006d59db10 0000000000000046 ffff88006d59daa0 ffffffff00000000
> > [  454.834094]  ffff88006deb4500 ffff88006d59dfd8 ffff88006d59dfd8 0000000000013b40
> > [  454.836788]  ffffffff81a0b020 ffff88006deb4500 ffff88006d59dad0 000000016d59dad0
> > [  454.839453] Call Trace:
> > [  454.842098]  [<ffffffff810d5904>] ? lock_page+0x3e/0x3e
> > [  454.844738]  [<ffffffff810d5904>] ? lock_page+0x3e/0x3e
> > [  454.847303]  [<ffffffff8147a7c9>] io_schedule+0x63/0x7e
> > [  454.849877]  [<ffffffff810d5912>] sleep_on_page+0xe/0x12
> > [  454.852469]  [<ffffffff8147aea9>] __wait_on_bit+0x48/0x7b
> > [  454.855021]  [<ffffffff810d5a8c>] wait_on_page_bit+0x72/0x74
> > [  454.857583]  [<ffffffff8106e88b>] ? autoremove_wake_function+0x3d/0x3d
> > [  454.860171]  [<ffffffff810d5b6b>] filemap_fdatawait_range+0x84/0x163
> > [  454.862744]  [<ffffffff810d5c6e>] filemap_fdatawait+0x24/0x26
> > [  454.865299]  [<ffffffff811c94a2>] jbd2_journal_commit_transaction+0x922/0x1194
> 
> But that seems to be waiting for a page lock.  Probably the same page
> lock held by the flusher thread?  Looks like tar is stuck in the same
> boat down below.

To test the theory, Chris asked me to try with data=ordered.
Unfortunately, the deadlock still shows up.  This is what I get.

James

---

[  263.749738] BUG: soft lockup - CPU#2 stuck for 67s! [kswapd0:46]
[  263.751207] Modules linked in: netconsole configfs cpufreq_ondemand acpi_cpufreq freq_table mperf snd_hda_codec_hdmi snd_hda_codec_conexant arc4 snd_hda_intel snd_hda_codec snd_hwdep iwlagn snd_seq snd_seq_device uvcvideo mac80211 e1000e btusb videodev snd_pcm v4l2_compat_ioctl32 snd_timer bluetooth i2c_i801 snd cfg80211 microcode iTCO_wdt xhci_hcd iTCO_vendor_support wmi pcspkr joydev soundcore snd_page_alloc rfkill uinput ipv6 sdhci_pci sdhci mmc_core i915 drm_kms_helper drm i2c_algo_bit i2c_core video [last unloaded: scsi_wait_scan]
[  263.756081] CPU 2 
[  263.756091] Modules linked in: netconsole configfs cpufreq_ondemand acpi_cpufreq freq_table mperf snd_hda_codec_hdmi snd_hda_codec_conexant arc4 snd_hda_intel snd_hda_codec snd_hwdep iwlagn snd_seq snd_seq_device uvcvideo mac80211 e1000e btusb videodev snd_pcm v4l2_compat_ioctl32 snd_timer bluetooth i2c_i801 snd cfg80211 microcode iTCO_wdt xhci_hcd iTCO_vendor_support wmi pcspkr joydev soundcore snd_page_alloc rfkill uinput ipv6 sdhci_pci sdhci mmc_core i915 drm_kms_helper drm i2c_algo_bit i2c_core video [last unloaded: scsi_wait_scan]
[  263.763033] 
[  263.764761] Pid: 46, comm: kswapd0 Not tainted 2.6.39-rc4+ #1 LENOVO 4170CTO/4170CTO
[  263.766509] RIP: 0010:[<ffffffff810e1fa2>]  [<ffffffff810e1fa2>] shrink_slab+0x86/0x166
[  263.768256] RSP: 0018:ffff8800709ebda0  EFLAGS: 00000206
[  263.769976] RAX: 0000000000000000 RBX: ffff8800709ebde0 RCX: 0000000000000002
[  263.771683] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffffff81a44e50
[  263.773378] RBP: ffff8800709ebde0 R08: 0000000000000000 R09: 000000000000a2b2
[  263.775053] R10: 0000000000000002 R11: ffffffff81a44e50 R12: ffffffff8148300e
[  263.776728] R13: ffff8800709ebe58 R14: 0000000000000004 R15: 00000000000178f9
[  263.778408] FS:  0000000000000000(0000) GS:ffff880100280000(0000) knlGS:0000000000000000
[  263.780098] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[  263.781777] CR2: 0000000001091018 CR3: 0000000001a03000 CR4: 00000000000406e0
[  263.783458] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  263.785140] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
[  263.786811] Process kswapd0 (pid: 46, threadinfo ffff8800709ea000, task ffff88006dfa8000)
[  263.788491] Stack:
[  263.790153]  000000000000003d 0000000000000080 ffff880000000000 ffff8801005e6700
[  263.791810]  ffff8801005e6000 0000000000000002 0000000000000000 000000000000000c
[  263.793426]  ffff8800709ebee0 ffffffff810e4bcc 0000000000000003 ffff88006dfa8000
[  263.795038] Call Trace:
[  263.796614]  [<ffffffff810e4bcc>] kswapd+0x533/0x798
[  263.798183]  [<ffffffff810e4699>] ? mem_cgroup_shrink_node_zone+0xe3/0xe3
[  263.799755]  [<ffffffff8106e157>] kthread+0x84/0x8c
[  263.801328]  [<ffffffff81483764>] kernel_thread_helper+0x4/0x10
[  263.802904]  [<ffffffff8106e0d3>] ? kthread_worker_fn+0x148/0x148
[  263.804434]  [<ffffffff81483760>] ? gs_change+0x13/0x13
[  263.805914] Code: 83 eb 10 e9 ce 00 00 00 44 89 f2 31 f6 48 89 df ff 13 48 63 4b 08 4c 63 e8 48 8b 45 c8 31 d2 48 f7 f1 31 d2 49 0f af c5 49 f7 f7 
[  263.806080]  03 43 20 48 85 c0 48 89 43 20 79 18 48 8b 33 48 89 c2 48 c7 
[  263.809185] Call Trace:
[  263.810748]  [<ffffffff810e4bcc>] kswapd+0x533/0x798
[  263.812327]  [<ffffffff810e4699>] ? mem_cgroup_shrink_node_zone+0xe3/0xe3
[  263.813913]  [<ffffffff8106e157>] kthread+0x84/0x8c
[  263.815486]  [<ffffffff81483764>] kernel_thread_helper+0x4/0x10
[  263.817053]  [<ffffffff8106e0d3>] ? kthread_worker_fn+0x148/0x148
[  263.818621]  [<ffffffff81483760>] ? gs_change+0x13/0x13
[  272.421176] SysRq : Show backtrace of all active CPUs
[  272.422748] sending NMI to all CPUs:
[  272.424304] NMI backtrace for cpu 2
[  272.426364] CPU 2 
[  272.426380] Modules linked in: netconsole configfs cpufreq_ondemand acpi_cpufreq freq_table mperf snd_hda_codec_hdmi snd_hda_codec_conexant arc4 snd_hda_intel snd_hda_codec snd_hwdep iwlagn snd_seq snd_seq_device uvcvideo mac80211 e1000e btusb videodev snd_pcm v4l2_compat_ioctl32 snd_timer bluetooth i2c_i801 snd cfg80211 microcode iTCO_wdt xhci_hcd iTCO_vendor_support wmi pcspkr joydev soundcore snd_page_alloc rfkill uinput ipv6 sdhci_pci sdhci mmc_core i915 drm_kms_helper drm i2c_algo_bit i2c_core video [last unloaded: scsi_wait_scan]
[  272.435128] 
[  272.437299] Pid: 46, comm: kswapd0 Not tainted 2.6.39-rc4+ #1 LENOVO 4170CTO/4170CTO
[  272.439506] RIP: 0010:[<ffffffff810d9b6e>]  [<ffffffff810d9b6e>] zone_watermark_ok_safe+0xa8/0xae
[  272.441728] RSP: 0018:ffff8800709ebd80  EFLAGS: 00000282
[  272.443916] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
[  272.446101] RDX: 0000000000000813 RSI: 0000000000000000 RDI: ffff8801005e6000
[  272.448258] RBP: ffff8800709ebd90 R08: 0000000000000000 R09: 00000000000007f0
[  272.450362] R10: 0000000000000002 R11: 00000000000000cd R12: ffff8801005e6000
[  272.452399] R13: 0000000000000000 R14: 0000000000000000 R15: ffff8801005e6000
[  272.454367] FS:  0000000000000000(0000) GS:ffff880100280000(0000) knlGS:0000000000000000
[  272.456306] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[  272.458188] CR2: 0000000001091018 CR3: 0000000001a03000 CR4: 00000000000406e0
[  272.460021] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  272.461789] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
[  272.463503] Process kswapd0 (pid: 46, threadinfo ffff8800709ea000, task ffff88006dfa8000)
[  272.465234] Stack:
[  272.466929]  0000000000000000 0000000000000000 ffff8800709ebde0 ffffffff810e1e8a
[  272.468675]  ffff880000000002 ffffffff8106e701 ffff880000000002 ffff8801005e6000
[  272.470406]  ffff8801005f9e68 0000000000000002 0000000000000000 0000000000000000
[  272.472144] Call Trace:
[  272.473850]  [<ffffffff810e1e8a>] sleeping_prematurely.part.11+0x6e/0xd2
[  272.475582]  [<ffffffff8106e701>] ? prepare_to_wait_exclusive+0x67/0x77
[  272.477304]  [<ffffffff810e47fe>] kswapd+0x165/0x798
[  272.479015]  [<ffffffff8106e84e>] ? remove_wait_queue+0x3a/0x3a
[  272.480712]  [<ffffffff810e4699>] ? mem_cgroup_shrink_node_zone+0xe3/0xe3
[  272.482414]  [<ffffffff8106e157>] kthread+0x84/0x8c
[  272.484106]  [<ffffffff81483764>] kernel_thread_helper+0x4/0x10
[  272.485809]  [<ffffffff8106e0d3>] ? kthread_worker_fn+0x148/0x148
[  272.487507]  [<ffffffff81483760>] ? gs_change+0x13/0x13
[  272.489175] Code: a6 00 48 8b 55 d8 8b 4d d0 44 8b 45 c8 7c c0 45 31 c9 4d 85 ed 4d 0f 49 cd 44 89 e6 48 89 df e8 5e f4 ff ff 48 83 c4 20 5b 41 5c 
[  272.489409]  5d 41 5e 5d c3 55 48 89 e5 0f 1f 44 00 00 bf da 00 02 00 e8 
[  272.492911] Call Trace:
[  272.494651]  [<ffffffff810e1e8a>] sleeping_prematurely.part.11+0x6e/0xd2
[  272.496408]  [<ffffffff8106e701>] ? prepare_to_wait_exclusive+0x67/0x77
[  272.498173]  [<ffffffff810e47fe>] kswapd+0x165/0x798
[  272.499926]  [<ffffffff8106e84e>] ? remove_wait_queue+0x3a/0x3a
[  272.501678]  [<ffffffff810e4699>] ? mem_cgroup_shrink_node_zone+0xe3/0xe3
[  272.503444]  [<ffffffff8106e157>] kthread+0x84/0x8c
[  272.505198]  [<ffffffff81483764>] kernel_thread_helper+0x4/0x10
[  272.506968]  [<ffffffff8106e0d3>] ? kthread_worker_fn+0x148/0x148
[  272.508691]  [<ffffffff81483760>] ? gs_change+0x13/0x13
[  272.510355] NMI backtrace for cpu 1
[  272.511996] CPU 1 
[  272.512011] Modules linked in: netconsole configfs cpufreq_ondemand acpi_cpufreq freq_table mperf snd_hda_codec_hdmi snd_hda_codec_conexant arc4 snd_hda_intel snd_hda_codec snd_hwdep iwlagn snd_seq snd_seq_device uvcvideo mac80211 e1000e btusb videodev snd_pcm v4l2_compat_ioctl32 snd_timer bluetooth i2c_i801 snd cfg80211 microcode iTCO_wdt xhci_hcd iTCO_vendor_support wmi pcspkr joydev soundcore snd_page_alloc rfkill uinput ipv6 sdhci_pci sdhci mmc_core i915 drm_kms_helper drm i2c_algo_bit i2c_core video [last unloaded: scsi_wait_scan]
[  272.519303] 
[  272.521156] Pid: 0, comm: kworker/0:0 Not tainted 2.6.39-rc4+ #1 LENOVO 4170CTO/4170CTO
[  272.523190] RIP: 0010:[<ffffffff81275d36>]  [<ffffffff81275d36>] intel_idle+0xaa/0x100
[  272.525110] RSP: 0018:ffff880071587e68  EFLAGS: 00000046
[  272.527018] RAX: 0000000000000010 RBX: 0000000000000004 RCX: 0000000000000001
[  272.528960] RDX: 0000000000000000 RSI: ffff880071587fd8 RDI: ffffffff81a0e640
[  272.530906] RBP: ffff880071587eb8 R08: 00000000000004af R09: 00000000000003e5
[  272.532800] R10: ffffffff00000001 R11: ffff880100253b40 R12: 0000000000000010
[  272.534634] R13: 12187a34a107f726 R14: 0000000000000002 R15: 0000000000000001
[  272.536399] FS:  0000000000000000(0000) GS:ffff880100240000(0000) knlGS:0000000000000000
[  272.538145] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[  272.539879] CR2: 00000037da2830e4 CR3: 0000000001a03000 CR4: 00000000000406e0
[  272.541614] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  272.543329] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
[  272.545026] Process kworker/0:0 (pid: 0, threadinfo ffff880071586000, task ffff880071589700)
[  272.546756] Stack:
[  272.548457]  ffff880071587e88 ffffffff810731c0 ffff880100251290 0000000000011290
[  272.550225]  ffff880071587eb8 000000018139c97a ffffe8ffffc40170 ffffe8ffffc40170
[  272.552009]  ffffe8ffffc40240 0000000000000000 ffff880071587ef8 ffffffff8139b868
[  272.553780] Call Trace:
[  272.555514]  [<ffffffff810731c0>] ? pm_qos_request+0x3e/0x45
[  272.557259]  [<ffffffff8139b868>] cpuidle_idle_call+0xe7/0x166
[  272.559005]  [<ffffffff81008321>] cpu_idle+0xa5/0xdf
[  272.560755]  [<ffffffff8146ae57>] start_secondary+0x223/0x225
[  272.562485] Code: 28 e0 ff ff 80 e2 08 75 22 31 d2 48 83 c0 10 48 89 d1 0f 01 c8 0f ae f0 48 8b 86 38 e0 ff ff a8 08 75 08 b1 01 4c 89 e0 0f 01 c9 <e8> 23 09 e0 ff 4c 29 e8 48 89 c7 e8 ab 29 de ff 4c 69 e0 40 42 
[  272.566380] Call Trace:
[  272.568193]  [<ffffffff810731c0>] ? pm_qos_request+0x3e/0x45
[  272.570031]  [<ffffffff8139b868>] cpuidle_idle_call+0xe7/0x166
[  272.571855]  [<ffffffff81008321>] cpu_idle+0xa5/0xdf
[  272.573658]  [<ffffffff8146ae57>] start_secondary+0x223/0x225
[  272.575463] NMI backtrace for cpu 0
[  272.576865] CPU 0 
[  272.576875] Modules linked in: netconsole configfs cpufreq_ondemand acpi_cpufreq freq_table mperf snd_hda_codec_hdmi snd_hda_codec_conexant arc4 snd_hda_intel snd_hda_codec snd_hwdep iwlagn snd_seq snd_seq_device uvcvideo mac80211 e1000e btusb videodev snd_pcm v4l2_compat_ioctl32 snd_timer bluetooth i2c_i801 snd cfg80211 microcode iTCO_wdt xhci_hcd iTCO_vendor_support wmi pcspkr joydev soundcore snd_page_alloc rfkill uinput ipv6 sdhci_pci sdhci mmc_core i915 drm_kms_helper drm i2c_algo_bit i2c_core video [last unloaded: scsi_wait_scan]
[  272.582932] 
[  272.584474] Pid: 0, comm: swapper Not tainted 2.6.39-rc4+ #1 LENOVO 4170CTO/4170CTO
[  272.586057] RIP: 0010:[<ffffffff81232e6e>]  [<ffffffff81232e6e>] __const_udelay+0x23/0x2e
[  272.587604] RSP: 0018:ffff880100203bf8  EFLAGS: 00000887
[  272.589120] RAX: 00000000fa1c3100 RBX: 0000000000002710 RCX: 0000000000000040
[  272.590635] RDX: 000000000026074e RSI: 0000000000000100 RDI: 0000000000418958
[  272.592139] RBP: ffff880100203bf8 R08: 000000008b000052 R09: 0000000000000000
[  272.593644] R10: 0000000000000000 R11: 0000000000000003 R12: 0000000000000008
[  272.595143] R13: 000000000000006c R14: 0000000000000002 R15: 0000000000000001
[  272.596635] FS:  0000000000000000(0000) GS:ffff880100200000(0000) knlGS:0000000000000000
[  272.598139] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[  272.599637] CR2: 00000037d909e270 CR3: 000000006f2d7000 CR4: 00000000000406f0
[  272.601151] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  272.602669] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
[  272.604187] Process swapper (pid: 0, threadinfo ffffffff81a00000, task ffffffff81a0b020)
[  272.605715] Stack:
[  272.607238]  ffff880100203c18 ffffffff8102166e 0000000000000000 ffffffff81a5e690
[  272.608756]  ffff880100203c28 ffffffff812be0ad ffff880100203c68 ffffffff812be310
[  272.610226]  ffff880037d01900 0000000000000026 ffff880037d01900 0000000000000001
[  272.611653] Call Trace:
[  272.613026]  <IRQ> 
[  272.614388]  [<ffffffff8102166e>] arch_trigger_all_cpu_backtrace+0x76/0x88
[  272.615761]  [<ffffffff812be0ad>] sysrq_handle_showallcpus+0xe/0x10
[  272.617118]  [<ffffffff812be310>] __handle_sysrq+0xa2/0x13c
[  272.618465]  [<ffffffff812be514>] sysrq_filter+0x112/0x16e
[  272.619814]  [<ffffffff81365764>] input_pass_event+0x94/0xcc
[  272.621158]  [<ffffffff81366bf1>] input_handle_event+0x480/0x48f
[  272.622497]  [<ffffffff810483af>] ? walk_tg_tree.constprop.71+0x28/0x94
[  272.623844]  [<ffffffff81366cf2>] input_event+0x69/0x87
[  272.625172]  [<ffffffff8136c17b>] atkbd_interrupt+0x4c1/0x58e
[  272.626489]  [<ffffffff81361b2e>] serio_interrupt+0x45/0x7f
[  272.627791]  [<ffffffff81362870>] i8042_interrupt+0x299/0x2ab
[  272.629085]  [<ffffffff8100eb79>] ? native_sched_clock+0x34/0x36
[  272.630383]  [<ffffffff810a95d5>] handle_irq_event_percpu+0x5f/0x198
[  272.631675]  [<ffffffff810a9746>] handle_irq_event+0x38/0x56
[  272.632946]  [<ffffffff81022e0e>] ? ack_apic_edge+0x25/0x29
[  272.634205]  [<ffffffff810ab71a>] handle_edge_irq+0x9d/0xc0
[  272.635455]  [<ffffffff8100ab9d>] handle_irq+0x88/0x8e
[  272.636699]  [<ffffffff8148409d>] do_IRQ+0x4d/0xa5
[  272.637927]  [<ffffffff8147c253>] common_interrupt+0x13/0x13
[  272.639155]  <EOI> 
[  272.640374]  [<ffffffff8100e6cd>] ? paravirt_read_tsc+0x9/0xd
[  272.641612]  [<ffffffff81275d67>] ? intel_idle+0xdb/0x100
[  272.642840]  [<ffffffff81275d46>] ? intel_idle+0xba/0x100
[  272.644012]  [<ffffffff8139b868>] cpuidle_idle_call+0xe7/0x166
[  272.645115]  [<ffffffff81008321>] cpu_idle+0xa5/0xdf
[  272.646215]  [<ffffffff8145a91e>] rest_init+0x72/0x74
[  272.647313]  [<ffffffff81b59b9f>] start_kernel+0x3de/0x3e9
[  272.648411]  [<ffffffff81b592c4>] x86_64_start_reservations+0xaf/0xb3
[  272.649514]  [<ffffffff81b59140>] ? early_idt_handlers+0x140/0x140
[  272.650624]  [<ffffffff81b593ca>] x86_64_start_kernel+0x102/0x111
[  272.651725] Code: ff 15 37 2b 82 00 5d c3 55 48 89 e5 0f 1f 44 00 00 65 48 8b 14 25 98 3a 01 00 48 8d 04 bd 00 00 00 00 48 69 d2 fa 00 00 00 f7 e2 
[  272.651891]  8d 7a 01 e8 c3 ff ff ff 5d c3 55 48 89 e5 0f 1f 44 00 00 48 
[  272.654210] Call Trace:
[  272.655298]  <IRQ>  [<ffffffff8102166e>] arch_trigger_all_cpu_backtrace+0x76/0x88
[  272.656372]  [<ffffffff812be0ad>] sysrq_handle_showallcpus+0xe/0x10
[  272.657421]  [<ffffffff812be310>] __handle_sysrq+0xa2/0x13c
[  272.658456]  [<ffffffff812be514>] sysrq_filter+0x112/0x16e
[  272.659480]  [<ffffffff81365764>] input_pass_event+0x94/0xcc
[  272.660486]  [<ffffffff81366bf1>] input_handle_event+0x480/0x48f
[  272.661487]  [<ffffffff810483af>] ? walk_tg_tree.constprop.71+0x28/0x94
[  272.662497]  [<ffffffff81366cf2>] input_event+0x69/0x87
[  272.663502]  [<ffffffff8136c17b>] atkbd_interrupt+0x4c1/0x58e
[  272.664519]  [<ffffffff81361b2e>] serio_interrupt+0x45/0x7f
[  272.665525]  [<ffffffff81362870>] i8042_interrupt+0x299/0x2ab
[  272.666522]  [<ffffffff8100eb79>] ? native_sched_clock+0x34/0x36
[  272.667509]  [<ffffffff810a95d5>] handle_irq_event_percpu+0x5f/0x198
[  272.668492]  [<ffffffff810a9746>] handle_irq_event+0x38/0x56
[  272.669467]  [<ffffffff81022e0e>] ? ack_apic_edge+0x25/0x29
[  272.670445]  [<ffffffff810ab71a>] handle_edge_irq+0x9d/0xc0
[  272.671408]  [<ffffffff8100ab9d>] handle_irq+0x88/0x8e
[  272.672357]  [<ffffffff8148409d>] do_IRQ+0x4d/0xa5
[  272.673284]  [<ffffffff8147c253>] common_interrupt+0x13/0x13
[  272.674225]  <EOI>  [<ffffffff8100e6cd>] ? paravirt_read_tsc+0x9/0xd
[  272.675166]  [<ffffffff81275d67>] ? intel_idle+0xdb/0x100
[  272.676098]  [<ffffffff81275d46>] ? intel_idle+0xba/0x100
[  272.677032]  [<ffffffff8139b868>] cpuidle_idle_call+0xe7/0x166
[  272.677951]  [<ffffffff81008321>] cpu_idle+0xa5/0xdf
[  272.678865]  [<ffffffff8145a91e>] rest_init+0x72/0x74
[  272.679770]  [<ffffffff81b59b9f>] start_kernel+0x3de/0x3e9
[  272.680685]  [<ffffffff81b592c4>] x86_64_start_reservations+0xaf/0xb3
[  272.681603]  [<ffffffff81b59140>] ? early_idt_handlers+0x140/0x140
[  272.682526]  [<ffffffff81b593ca>] x86_64_start_kernel+0x102/0x111
[  272.683442] NMI backtrace for cpu 3
[  272.684688] CPU 3 
[  272.684705] Modules linked in: netconsole configfs cpufreq_ondemand acpi_cpufreq freq_table mperf snd_hda_codec_hdmi snd_hda_codec_conexant arc4 snd_hda_intel snd_hda_codec snd_hwdep iwlagn snd_seq snd_seq_device uvcvideo mac80211 e1000e btusb videodev snd_pcm v4l2_compat_ioctl32 snd_timer bluetooth i2c_i801 snd cfg80211 microcode iTCO_wdt xhci_hcd iTCO_vendor_support wmi pcspkr joydev soundcore snd_page_alloc rfkill uinput ipv6 sdhci_pci sdhci mmc_core i915 drm_kms_helper drm i2c_algo_bit i2c_core video [last unloaded: scsi_wait_scan]
[  272.690650] 
[  272.692143] Pid: 0, comm: kworker/0:1 Not tainted 2.6.39-rc4+ #1 LENOVO 4170CTO/4170CTO
[  272.693726] RIP: 0010:[<ffffffff81275d36>]  [<ffffffff81275d36>] intel_idle+0xaa/0x100
[  272.695312] RSP: 0018:ffff8800715dfe68  EFLAGS: 00000046
[  272.696892] RAX: 0000000000000030 RBX: 0000000000000010 RCX: 0000000000000001
[  272.698508] RDX: 0000000000000000 RSI: ffff8800715dffd8 RDI: ffffffff81a0e640
[  272.700117] RBP: ffff8800715dfeb8 R08: 000000000000006d R09: 00000000000003e4
[  272.701740] R10: ffffffff00000003 R11: ffff8801002d3b40 R12: 0000000000000030
[  272.703369] R13: 12187a34a107edc5 R14: 0000000000000004 R15: 0000000000000003
[  272.704985] FS:  0000000000000000(0000) GS:ffff8801002c0000(0000) knlGS:0000000000000000
[  272.706626] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[  272.708277] CR2: 00000037d904c480 CR3: 0000000001a03000 CR4: 00000000000406e0
[  272.709952] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  272.711633] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
[  272.713299] Process kworker/0:1 (pid: 0, threadinfo ffff8800715de000, task ffff8800715e1700)
[  272.715001] Stack:
[  272.716681]  ffff8800715dfe88 ffffffff810731c0 ffff8801002d1290 0000000000011290
[  272.718411]  ffff8800715dfeb8 000000038139c97a ffffe8ffffcc0170 ffffe8ffffcc0170
[  272.720153]  ffffe8ffffcc0300 0000000000000000 ffff8800715dfef8 ffffffff8139b868
[  272.721848] Call Trace:
[  272.723465]  [<ffffffff810731c0>] ? pm_qos_request+0x3e/0x45
[  272.725095]  [<ffffffff8139b868>] cpuidle_idle_call+0xe7/0x166
[  272.726712]  [<ffffffff81008321>] cpu_idle+0xa5/0xdf
[  272.728304]  [<ffffffff8146ae57>] start_secondary+0x223/0x225
[  272.729891] Code: 28 e0 ff ff 80 e2 08 75 22 31 d2 48 83 c0 10 48 89 d1 0f 01 c8 0f ae f0 48 8b 86 38 e0 ff ff a8 08 75 08 b1 01 4c 89 e0 0f 01 c9 <e8> 23 09 e0 ff 4c 29 e8 48 89 c7 e8 ab 29 de ff 4c 69 e0 40 42 
[  272.733594] Call Trace:
[  272.735317]  [<ffffffff810731c0>] ? pm_qos_request+0x3e/0x45
[  272.737068]  [<ffffffff8139b868>] cpuidle_idle_call+0xe7/0x166
[  272.738815]  [<ffffffff81008321>] cpu_idle+0xa5/0xdf
[  272.740554]  [<ffffffff8146ae57>] start_secondary+0x223/0x225
[  274.865072] SysRq : Show Blocked State
[  274.866350]   task                        PC stack   pid father
[  274.867634] jbd2/dm-1-8     D 0000000000000000     0   363      2 0x00000000
[  274.868930]  ffff880037d05ba0 0000000000000046 ffff880037d05b30 ffffffff00000000
[  274.870242]  ffff880037acc500 ffff880037d05fd8 ffff880037d05fd8 0000000000013b40
[  274.871555]  ffffffff81a0b020 ffff880037acc500 ffff8801002143c0 00000001005bd1d0
[  274.872869] Call Trace:
[  274.874168]  [<ffffffff81142202>] ? wait_on_buffer+0x3a/0x3a
[  274.875485]  [<ffffffff81142202>] ? wait_on_buffer+0x3a/0x3a
[  274.876791]  [<ffffffff8147a7c9>] io_schedule+0x63/0x7e
[  274.878089]  [<ffffffff81142210>] sleep_on_buffer+0xe/0x12
[  274.879382]  [<ffffffff8147aea9>] __wait_on_bit+0x48/0x7b
[  274.880684]  [<ffffffff8147af4e>] out_of_line_wait_on_bit+0x72/0x7d
[  274.881986]  [<ffffffff81142202>] ? wait_on_buffer+0x3a/0x3a
[  274.883290]  [<ffffffff8106e88b>] ? autoremove_wake_function+0x3d/0x3d
[  274.884601]  [<ffffffff811421c6>] __wait_on_buffer+0x26/0x28
[  274.885912]  [<ffffffff811c8a3c>] wait_on_buffer+0x35/0x39
[  274.887221]  [<ffffffff811c9633>] jbd2_journal_commit_transaction+0xab3/0x1194
[  274.888547]  [<ffffffff81008714>] ? __switch_to+0xc6/0x220
[  274.889864]  [<ffffffff81080be3>] ? arch_local_irq_save+0x15/0x1b
[  274.891178]  [<ffffffff8147be3a>] ? _raw_spin_lock_irqsave+0x12/0x2f
[  274.892500]  [<ffffffff8105ffef>] ? lock_timer_base+0x2c/0x52
[  274.893776]  [<ffffffff811cd3b6>] kjournald2+0xc9/0x20a
[  274.895001]  [<ffffffff8106e84e>] ? remove_wait_queue+0x3a/0x3a
[  274.896179]  [<ffffffff811cd2ed>] ? commit_timeout+0x10/0x10
[  274.897328]  [<ffffffff8106e157>] kthread+0x84/0x8c
[  274.898465]  [<ffffffff81483764>] kernel_thread_helper+0x4/0x10
[  274.899591]  [<ffffffff8106e0d3>] ? kthread_worker_fn+0x148/0x148
[  274.900708]  [<ffffffff81483760>] ? gs_change+0x13/0x13
[  274.901813] flush-253:1     D 0000000000000000     0   419      2 0x00000000
[  274.902938]  ffff8800706bf760 0000000000000046 ffff8800706bf6f0 ffffffff00000000
[  274.904067]  ffff880037afc500 ffff8800706bffd8 ffff8800706bffd8 0000000000013b40
[  274.905198]  ffffffff81a0b020 ffff880037afc500 ffff8800706bf720 00000001706bf720
[  274.906327] Call Trace:
[  274.907430]  [<ffffffff8147a7c9>] io_schedule+0x63/0x7e
[  274.908536]  [<ffffffff81217b00>] get_request_wait+0x102/0x18b
[  274.909633]  [<ffffffff8106e84e>] ? remove_wait_queue+0x3a/0x3a
[  274.910729]  [<ffffffff81218841>] __make_request+0x18a/0x2b8
[  274.911826]  [<ffffffff81217538>] generic_make_request+0x2a9/0x323
[  274.912908]  [<ffffffff810d79c9>] ? mempool_alloc_slab+0x15/0x17
[  274.913979]  [<ffffffff81217690>] submit_bio+0xde/0xfd
[  274.915040]  [<ffffffff81145fc0>] ? bio_alloc_bioset+0x4c/0xc3
[  274.916104]  [<ffffffff810ea6e1>] ? inc_zone_page_state+0x27/0x29
[  274.917161]  [<ffffffff81141c42>] submit_bh+0xe6/0x105
[  274.918208]  [<ffffffff8114317e>] __block_write_full_page+0x1e7/0x2d7
[  274.919261]  [<ffffffff81147540>] ? thaw_bdev+0x79/0x79
[  274.920306]  [<ffffffff81144967>] ? bit_spin_lock.constprop.20+0x2c/0x2c
[  274.921361]  [<ffffffff81144967>] ? bit_spin_lock.constprop.20+0x2c/0x2c
[  274.922411]  [<ffffffff81147540>] ? thaw_bdev+0x79/0x79
[  274.923396]  [<ffffffff81144851>] block_write_full_page_endio+0x8a/0x97
[  274.924324]  [<ffffffff81144873>] block_write_full_page+0x15/0x17
[  274.925248]  [<ffffffff81147297>] blkdev_writepage+0x18/0x1a
[  274.926168]  [<ffffffff810dd3c1>] __writepage+0x15/0x2e
[  274.927083]  [<ffffffff810dd1fc>] write_cache_pages+0x209/0x330
[  274.928001]  [<ffffffff810dd3ac>] ? set_page_dirty_lock+0x33/0x33
[  274.928923]  [<ffffffff8104e4c1>] ? find_busiest_group+0x253/0x8b9
[  274.929834]  [<ffffffff81198ba9>] ? ext4_da_writepages+0x85/0x44d
[  274.930744]  [<ffffffff810dd363>] generic_writepages+0x40/0x56
[  274.931646]  [<ffffffff810ddeb4>] do_writepages+0x21/0x2a
[  274.932536]  [<ffffffff8113cbb7>] writeback_single_inode+0xb2/0x1bc
[  274.933430]  [<ffffffff8113cf03>] writeback_sb_inodes+0xcd/0x161
[  274.934317]  [<ffffffff8113d407>] writeback_inodes_wb+0x119/0x12b
[  274.935194]  [<ffffffff8113d607>] wb_writeback+0x1ee/0x335
[  274.936061]  [<ffffffff81045c65>] ? hrtick_update+0x32/0x34
[  274.936941]  [<ffffffff810dd79a>] ? global_dirty_limits+0x2b/0xd1
[  274.936943]  [<ffffffff8113d8cd>] wb_do_writeback+0x17f/0x19d
[  274.936945]  [<ffffffff8113d973>] bdi_writeback_thread+0x88/0x1e5
[  274.936947]  [<ffffffff8113d8eb>] ? wb_do_writeback+0x19d/0x19d
[  274.936949]  [<ffffffff8106e157>] kthread+0x84/0x8c
[  274.936951]  [<ffffffff81483764>] kernel_thread_helper+0x4/0x10
[  274.936953]  [<ffffffff8106e0d3>] ? kthread_worker_fn+0x148/0x148
[  274.936956]  [<ffffffff81483760>] ? gs_change+0x13/0x13
[  274.936958] jbd2/dm-2-8     D ffff88006e17c700     0   787      2 0x00000000
[  274.936960]  ffff88006ffe5cd0 0000000000000046 0000000000000000 0000000000000000
[  274.936962]  ffff88006f0e0000 ffff88006ffe5fd8 ffff88006ffe5fd8 0000000000013b40
[  274.936964]  ffff8800715fc500 ffff88006f0e0000 ffff88006ffe5cd0 ffffffff8106e7c3
[  274.936966] Call Trace:
[  274.936968]  [<ffffffff8106e7c3>] ? prepare_to_wait+0x6c/0x78
[  274.936970]  [<ffffffff811c8d44>] jbd2_journal_commit_transaction+0x1c4/0x1194
[  274.936972]  [<ffffffff8104480b>] ? perf_event_task_sched_out+0x55/0x61
[  274.936974]  [<ffffffff8100eb84>] ? sched_clock+0x9/0xd
[  274.936977]  [<ffffffff810736dc>] ? sched_clock_cpu+0x42/0xc6
[  274.936979]  [<ffffffff8100804e>] ? load_TLS+0x10/0x14
[  274.936981]  [<ffffffff81008714>] ? __switch_to+0xc6/0x220
[  274.936983]  [<ffffffff8106e84e>] ? remove_wait_queue+0x3a/0x3a
[  274.936985]  [<ffffffff8105ffef>] ? lock_timer_base+0x2c/0x52
[  274.936987]  [<ffffffff8147be8c>] ? _raw_spin_unlock_irqrestore+0x17/0x19
[  274.936989]  [<ffffffff81060088>] ? try_to_del_timer_sync+0x73/0x81
[  274.936991]  [<ffffffff811cd3b6>] kjournald2+0xc9/0x20a
[  274.936993]  [<ffffffff8106e84e>] ? remove_wait_queue+0x3a/0x3a
[  274.936995]  [<ffffffff811cd2ed>] ? commit_timeout+0x10/0x10
[  274.936997]  [<ffffffff8106e157>] kthread+0x84/0x8c
[  274.936998]  [<ffffffff81483764>] kernel_thread_helper+0x4/0x10
[  274.937001]  [<ffffffff8106e0d3>] ? kthread_worker_fn+0x148/0x148
[  274.937003]  [<ffffffff81483760>] ? gs_change+0x13/0x13
[  274.937004] flush-253:2     D ffff88006d5a0000     0   851      2 0x00000000
[  274.937006]  ffff88006e45d760 0000000000000046 ffff880037a30000 0000000000000246
[  274.937008]  ffff88006d5a0000 ffff88006e45dfd8 ffff88006e45dfd8 0000000000013b40
[  274.937010]  ffff880071588000 ffff88006d5a0000 ffff88006e45d720 ffff88006e45d720
[  274.937011] Call Trace:
[  274.937013]  [<ffffffff8147a7c9>] io_schedule+0x63/0x7e
[  274.937015]  [<ffffffff81217b00>] get_request_wait+0x102/0x18b
[  274.937017]  [<ffffffff8106e84e>] ? remove_wait_queue+0x3a/0x3a
[  274.937019]  [<ffffffff81218841>] __make_request+0x18a/0x2b8
[  274.937020]  [<ffffffff81217538>] generic_make_request+0x2a9/0x323
[  274.937022]  [<ffffffff810d79c9>] ? mempool_alloc_slab+0x15/0x17
[  274.937024]  [<ffffffff81217690>] submit_bio+0xde/0xfd
[  274.937026]  [<ffffffff81145fc0>] ? bio_alloc_bioset+0x4c/0xc3
[  274.937028]  [<ffffffff810ea6e1>] ? inc_zone_page_state+0x27/0x29
[  274.937030]  [<ffffffff81141c42>] submit_bh+0xe6/0x105
[  274.937031]  [<ffffffff8114317e>] __block_write_full_page+0x1e7/0x2d7
[  274.937033]  [<ffffffff81147540>] ? thaw_bdev+0x79/0x79
[  274.937035]  [<ffffffff81144967>] ? bit_spin_lock.constprop.20+0x2c/0x2c
[  274.937037]  [<ffffffff81144967>] ? bit_spin_lock.constprop.20+0x2c/0x2c
[  274.937039]  [<ffffffff81147540>] ? thaw_bdev+0x79/0x79
[  274.937040]  [<ffffffff81144851>] block_write_full_page_endio+0x8a/0x97
[  274.937042]  [<ffffffff81144873>] block_write_full_page+0x15/0x17
[  274.937044]  [<ffffffff81147297>] blkdev_writepage+0x18/0x1a
[  274.937046]  [<ffffffff810dd3c1>] __writepage+0x15/0x2e
[  274.937048]  [<ffffffff810dd1fc>] write_cache_pages+0x209/0x330
[  274.937049]  [<ffffffff810dd3ac>] ? set_page_dirty_lock+0x33/0x33
[  274.937052]  [<ffffffff810dd363>] generic_writepages+0x40/0x56
[  274.937054]  [<ffffffff810ddeb4>] do_writepages+0x21/0x2a
[  274.937055]  [<ffffffff8113cbb7>] writeback_single_inode+0xb2/0x1bc
[  274.937057]  [<ffffffff8113cf03>] writeback_sb_inodes+0xcd/0x161
[  274.937059]  [<ffffffff8113d407>] writeback_inodes_wb+0x119/0x12b
[  274.937061]  [<ffffffff8113d607>] wb_writeback+0x1ee/0x335
[  274.937063]  [<ffffffff81080be3>] ? arch_local_irq_save+0x15/0x1b
[  274.937065]  [<ffffffff8147be3a>] ? _raw_spin_lock_irqsave+0x12/0x2f
[  274.937067]  [<ffffffff8113d891>] wb_do_writeback+0x143/0x19d
[  274.937069]  [<ffffffff8147acc7>] ? schedule_timeout+0xb0/0xde
[  274.937071]  [<ffffffff8113d973>] bdi_writeback_thread+0x88/0x1e5
[  274.937073]  [<ffffffff8113d8eb>] ? wb_do_writeback+0x19d/0x19d
[  274.937075]  [<ffffffff8106e157>] kthread+0x84/0x8c
[  274.937077]  [<ffffffff81483764>] kernel_thread_helper+0x4/0x10
[  274.937079]  [<ffffffff8106e0d3>] ? kthread_worker_fn+0x148/0x148
[  274.937081]  [<ffffffff81483760>] ? gs_change+0x13/0x13
[  274.937082] tar             D 0000000000000000     0   980    826 0x00000000
[  274.937084]  ffff88006f19f9e8 0000000000000086 00000000ffff9fd7 0000000000000000
[  274.937086]  ffff88006d670000 ffff88006f19ffd8 ffff88006f19ffd8 0000000000013b40
[  274.937088]  ffffffff81a0b020 ffff88006d670000 ffff88006f19f9b8 0000000100000282
[  274.937090] Call Trace:
[  274.937091]  [<ffffffff8147acbe>] schedule_timeout+0xa7/0xde
[  274.937093]  [<ffffffff8106015c>] ? del_timer+0x7a/0x7a
[  274.937095]  [<ffffffff8147abc2>] io_schedule_timeout+0x6f/0x98
[  274.937097]  [<ffffffff810ddc26>] balance_dirty_pages_ratelimited_nr+0x341/0x3b6
[  274.937100]  [<ffffffff810d5efb>] generic_file_buffered_write+0x1dc/0x23a
[  274.937102]  [<ffffffff810d6c9d>] __generic_file_aio_write+0x242/0x272
[  274.937105]  [<ffffffff810d6d2e>] generic_file_aio_write+0x61/0xba
[  274.937108]  [<ffffffff8118fe00>] ext4_file_write+0x1dc/0x234
[  274.937111]  [<ffffffff8111edab>] do_sync_write+0xbf/0xff
[  274.937112]  [<ffffffff8114b9fc>] ? fsnotify+0x1eb/0x217
[  274.937115]  [<ffffffff811f1866>] ? selinux_file_permission+0x58/0xb4
[  274.937118]  [<ffffffff811e9cfe>] ? security_file_permission+0x2e/0x33
[  274.937120]  [<ffffffff8111f196>] ? rw_verify_area+0xb0/0xcd
[  274.937122]  [<ffffffff8111f421>] vfs_write+0xac/0xf3
[  274.937123]  [<ffffffff8111f610>] sys_write+0x4a/0x6e
[  274.937126]  [<ffffffff81482642>] system_call_fastpath+0x16/0x1b
[  274.937128] make            D 0000000000000000     0  2252   2143 0x00000004
[  274.937130]  ffff88006d631948 0000000000000082 ffff88006d6318d8 ffffffff00000000
[  274.937132]  ffff880037d9dc00 ffff88006d631fd8 ffff88006d631fd8 0000000000013b40
[  274.937133]  ffffffff81a0b020 ffff880037d9dc00 ffff88006d631908 000000016d631908
[  274.937135] Call Trace:
[  274.937137]  [<ffffffff81142202>] ? wait_on_buffer+0x3a/0x3a
[  274.937139]  [<ffffffff8147a7c9>] io_schedule+0x63/0x7e
[  274.937140]  [<ffffffff81142210>] sleep_on_buffer+0xe/0x12
[  274.937142]  [<ffffffff8147ad9b>] __wait_on_bit_lock+0x46/0x8f
[  274.937144]  [<ffffffff8147ae56>] out_of_line_wait_on_bit_lock+0x72/0x7d
[  274.937146]  [<ffffffff81142202>] ? wait_on_buffer+0x3a/0x3a
[  274.937148]  [<ffffffff8106e88b>] ? autoremove_wake_function+0x3d/0x3d
[  274.937149]  [<ffffffff8114240b>] __lock_buffer+0x38/0x3c
[  274.937151]  [<ffffffff811c6ce9>] lock_buffer+0x39/0x3d
[  274.937153]  [<ffffffff811c8162>] do_get_write_access+0x70/0x38d
[  274.937155]  [<ffffffff811927b4>] ? __ext4_get_inode_loc+0x118/0x36d
[  274.937156]  [<ffffffff811991cc>] ? ext4_dirty_inode+0x33/0x4c
[  274.937158]  [<ffffffff811c8588>] jbd2_journal_get_write_access+0x2b/0x42
[  274.937161]  [<ffffffff811b5888>] __ext4_journal_get_write_access+0x58/0x66
[  274.937163]  [<ffffffff81195526>] ext4_reserve_inode_write+0x41/0x83
[  274.937165]  [<ffffffff811955e4>] ext4_mark_inode_dirty+0x7c/0x1f0
[  274.937167]  [<ffffffff8147c515>] ? page_fault+0x25/0x30
[  274.937169]  [<ffffffff811991cc>] ext4_dirty_inode+0x33/0x4c
[  274.937171]  [<ffffffff8113c3d6>] __mark_inode_dirty+0x2f/0x175
[  274.937173]  [<ffffffff811322c7>] touch_atime+0x10e/0x131
[  274.937176]  [<ffffffff810d7360>] generic_file_aio_read+0x5d9/0x640
[  274.937178]  [<ffffffff8111eeaa>] do_sync_read+0xbf/0xff
[  274.937180]  [<ffffffff811e9cfe>] ? security_file_permission+0x2e/0x33
[  274.937181]  [<ffffffff8111f196>] ? rw_verify_area+0xb0/0xcd
[  274.937183]  [<ffffffff8111f511>] vfs_read+0xa9/0xf0
[  274.937185]  [<ffffffff8111f5a2>] sys_read+0x4a/0x6e
[  274.937187]  [<ffffffff81482642>] system_call_fastpath+0x16/0x1b
[  274.937188] make            D 0000000000000000     0  2262   2254 0x00000004
[  274.937190]  ffff880070b2b948 0000000000000082 ffff880070b2b8d8 ffffffff00000000
[  274.937192]  ffff88006d428000 ffff880070b2bfd8 ffff880070b2bfd8 0000000000013b40
[  274.937194]  ffffffff81a0b020 ffff88006d428000 ffff880070b2b908 0000000170b2b908
[  274.937195] Call Trace:
[  274.937197]  [<ffffffff81142202>] ? wait_on_buffer+0x3a/0x3a
[  274.937199]  [<ffffffff8147a7c9>] io_schedule+0x63/0x7e
[  274.937200]  [<ffffffff81142210>] sleep_on_buffer+0xe/0x12
[  274.937202]  [<ffffffff8147ad9b>] __wait_on_bit_lock+0x46/0x8f
[  274.937204]  [<ffffffff8147ae56>] out_of_line_wait_on_bit_lock+0x72/0x7d
[  274.937205]  [<ffffffff81142202>] ? wait_on_buffer+0x3a/0x3a
[  274.937208]  [<ffffffff8106e88b>] ? autoremove_wake_function+0x3d/0x3d
[  274.937209]  [<ffffffff8114240b>] __lock_buffer+0x38/0x3c
[  274.937211]  [<ffffffff811c6ce9>] lock_buffer+0x39/0x3d
[  274.937212]  [<ffffffff811c8162>] do_get_write_access+0x70/0x38d
[  274.937214]  [<ffffffff811927b4>] ? __ext4_get_inode_loc+0x118/0x36d
[  274.937216]  [<ffffffff811991cc>] ? ext4_dirty_inode+0x33/0x4c
[  274.937217]  [<ffffffff811c8588>] jbd2_journal_get_write_access+0x2b/0x42
[  274.937219]  [<ffffffff811b5888>] __ext4_journal_get_write_access+0x58/0x66
[  274.937221]  [<ffffffff81195526>] ext4_reserve_inode_write+0x41/0x83
[  274.937223]  [<ffffffff811955e4>] ext4_mark_inode_dirty+0x7c/0x1f0
[  274.937225]  [<ffffffff8147c515>] ? page_fault+0x25/0x30
[  274.937227]  [<ffffffff811991cc>] ext4_dirty_inode+0x33/0x4c
[  274.937228]  [<ffffffff8113c3d6>] __mark_inode_dirty+0x2f/0x175
[  274.937230]  [<ffffffff811322c7>] touch_atime+0x10e/0x131
[  274.937232]  [<ffffffff810d7360>] generic_file_aio_read+0x5d9/0x640
[  274.937234]  [<ffffffff8111eeaa>] do_sync_read+0xbf/0xff
[  274.937236]  [<ffffffff811e9cfe>] ? security_file_permission+0x2e/0x33
[  274.937238]  [<ffffffff8111f196>] ? rw_verify_area+0xb0/0xcd
[  274.937240]  [<ffffffff8111f511>] vfs_read+0xa9/0xf0
[  274.937241]  [<ffffffff8111f5a2>] sys_read+0x4a/0x6e
[  274.937243]  [<ffffffff81482642>] system_call_fastpath+0x16/0x1b
[  274.937244] sh              D 0000000000000000     0  2270   2253 0x00000004
[  274.937246]  ffff8800709fd918 0000000000000086 ffff8800709fd8a8 ffffffff00000000
[  274.937248]  ffff880037f60000 ffff8800709fdfd8 ffff8800709fdfd8 0000000000013b40
[  274.937250]  ffff880071589700 ffff880037f60000 ffff8801002543c0 00000001005b89d0
[  274.937252] Call Trace:
[  274.937253]  [<ffffffff81142202>] ? wait_on_buffer+0x3a/0x3a
[  274.937255]  [<ffffffff8147a7c9>] io_schedule+0x63/0x7e
[  274.937256]  [<ffffffff81142210>] sleep_on_buffer+0xe/0x12
[  274.937258]  [<ffffffff8147ad9b>] __wait_on_bit_lock+0x46/0x8f
[  274.937260]  [<ffffffff8147ae56>] out_of_line_wait_on_bit_lock+0x72/0x7d
[  274.937261]  [<ffffffff81142202>] ? wait_on_buffer+0x3a/0x3a
[  274.937264]  [<ffffffff8106e88b>] ? autoremove_wake_function+0x3d/0x3d
[  274.937265]  [<ffffffff8114240b>] __lock_buffer+0x38/0x3c
[  274.937267]  [<ffffffff811c6ce9>] lock_buffer+0x39/0x3d
[  274.937268]  [<ffffffff811c8162>] do_get_write_access+0x70/0x38d
[  274.937270]  [<ffffffff81196fe4>] ? ext4_getblk+0x8e/0x153
[  274.937272]  [<ffffffff811c8588>] jbd2_journal_get_write_access+0x2b/0x42
[  274.937274]  [<ffffffff811b5888>] __ext4_journal_get_write_access+0x58/0x66
[  274.937276]  [<ffffffff8119c0ce>] ext4_add_entry+0x11f/0x8cf
[  274.937277]  [<ffffffff811429f3>] ? __brelse+0x15/0x33
[  274.937280]  [<ffffffff81191bac>] ? ext4_new_inode+0xc63/0xd0a
[  274.937282]  [<ffffffff8119c89c>] ext4_add_nondir+0x1e/0x67
[  274.937283]  [<ffffffff8119cb1b>] ext4_create+0xf5/0x13e
[  274.937286]  [<ffffffff81129b12>] vfs_create+0x6c/0x8e
[  274.937288]  [<ffffffff81129dad>] do_last+0x279/0x5ab
[  274.937289]  [<ffffffff8112ac73>] path_openat+0xc8/0x31c
[  274.937291]  [<ffffffff810f2332>] ? handle_mm_fault+0x1ac/0x1bf
[  274.937293]  [<ffffffff8112aeff>] do_filp_open+0x38/0x86
[  274.937295]  [<ffffffff81233de1>] ? might_fault+0x21/0x23
[  274.937298]  [<ffffffff8113477b>] ? alloc_fd+0x72/0x11d
[  274.937299]  [<ffffffff8111e995>] do_sys_open+0x6e/0x100
[  274.937301]  [<ffffffff8111ea47>] sys_open+0x20/0x22
[  274.937303]  [<ffffffff81482642>] system_call_fastpath+0x16/0x1b
[  274.937304] gcc             D 0000000000000000     0  2272   2271 0x00000004
[  274.937306]  ffff88006f197c58 0000000000000086 ffff88006f197d14 0000000000000000
[  274.937307]  ffff880037f64500 ffff88006f197fd8 ffff88006f197fd8 0000000000013b40
[  274.937309]  ffffffff81a0b020 ffff880037f64500 ffff88006f197c58 000000018106e7c3
[  274.937311] Call Trace:
[  274.937313]  [<ffffffff811c6fd5>] start_this_handle+0x2e8/0x465
[  274.937315]  [<ffffffff8106e84e>] ? remove_wait_queue+0x3a/0x3a
[  274.937317]  [<ffffffff811c7418>] jbd2__journal_start+0x94/0xda
[  274.937318]  [<ffffffff811c7471>] jbd2_journal_start+0x13/0x15
[  274.937320]  [<ffffffff811ab92a>] ext4_journal_start_sb+0x108/0x120
[  274.937323]  [<ffffffff8119cc24>] ext4_symlink+0xc0/0x212
[  274.937325]  [<ffffffff81129928>] vfs_symlink+0x54/0x74
[  274.937326]  [<ffffffff8112b40a>] sys_symlinkat+0x96/0xef
[  274.937329]  [<ffffffff81122d89>] ? sys_newstat+0x2a/0x33
[  274.937330]  [<ffffffff8112b479>] sys_symlink+0x16/0x18
[  274.937332]  [<ffffffff81482642>] system_call_fastpath+0x16/0x1b
[  274.937335] Sched Debug Version: v0.10, 2.6.39-rc4+ #1
[  274.937336] ktime                                   : 275382.978028
[  274.937337] sched_clk                               : 274937.334155
[  274.937338] cpu_clk                                 : 274937.334189
[  274.937339] jiffies                                 : 4294942678
[  274.937340] sched_clock_stable                      : 1
[  274.937341] 
[  274.937342] sysctl_sched
[  274.937342]   .sysctl_sched_latency                    : 18.000000
[  274.937344]   .sysctl_sched_min_granularity            : 2.250000
[  274.937345]   .sysctl_sched_wakeup_granularity         : 3.000000
[  274.937346]   .sysctl_sched_child_runs_first           : 0
[  274.937347]   .sysctl_sched_features                   : 7279
[  274.937348]   .sysctl_sched_tunable_scaling            : 1 (logaritmic)
[  274.937349] 
[  274.937350] cpu#0, 2492.220 MHz
[  274.937350]   .nr_running                    : 0
[  274.937351]   .load                          : 0
[  274.937352]   .nr_switches                   : 105039
[  274.937353]   .nr_load_updates               : 121182
[  274.937354]   .nr_uninterruptible            : 8
[  274.937355]   .next_balance                  : 4294.942607
[  274.937356]   .curr->pid                     : 0
[  274.937357]   .clock                         : 274864.523786
[  274.937359]   .cpu_load[0]                   : 0
[  274.937360]   .cpu_load[1]                   : 0
[  274.937361]   .cpu_load[2]                   : 0
[  274.937362]   .cpu_load[3]                   : 0
[  274.937363]   .cpu_load[4]                   : 0
[  274.937364]   .yld_count                     : 0
[  274.937364]   .sched_switch                  : 0
[  274.937365]   .sched_count                   : 107487
[  274.937366]   .sched_goidle                  : 43368
[  274.937367]   .avg_idle                      : 1000000
[  274.937368]   .ttwu_count                    : 56534
[  274.937369]   .ttwu_local                    : 53925
[  274.937370]   .bkl_count                     : 0
[  274.937371] 
[  274.937372] cfs_rq[0]:/system
[  274.937372]   .exec_clock                    : 5906.036519
[  274.937374]   .MIN_vruntime                  : 0.000001
[  274.937375]   .min_vruntime                  : 7138.254657
[  274.937376]   .max_vruntime                  : 0.000001
[  274.937377]   .spread                        : 0.000000
[  274.937378]   .spread0                       : -14302.149798
[  274.937379]   .nr_spread_over                : 0
[  274.937379]   .nr_running                    : 0
[  274.937380]   .load                          : 0
[  274.937381]   .load_avg                      : 159.999997
[  274.937382]   .load_period                   : 5.496191
[  274.937383]   .load_contrib                  : 29
[  274.937384]   .load_tg                       : 29
[  274.937385]   .se->exec_start                : 272446.423795
[  274.937386]   .se->vruntime                  : 21431.398168
[  274.937387]   .se->sum_exec_runtime          : 5906.111805
[  274.937388]   .se->statistics.wait_start     : 0.000000
[  274.937389]   .se->statistics.sleep_start    : 0.000000
[  274.937390]   .se->statistics.block_start    : 0.000000
[  274.937391]   .se->statistics.sleep_max      : 0.000000
[  274.937392]   .se->statistics.block_max      : 0.000000
[  274.937393]   .se->statistics.exec_max       : 15.266617
[  274.937394]   .se->statistics.slice_max      : 5.258344
[  274.937395]   .se->statistics.wait_max       : 7.305679
[  274.937396]   .se->statistics.wait_sum       : 190.506686
[  274.937397]   .se->statistics.wait_count     : 18946
[  274.937398]   .se->load.weight               : 2
[  274.937399] 
[  274.937399] cfs_rq[0]:/
[  274.937400]   .exec_clock                    : 17409.691339
[  274.937401]   .MIN_vruntime                  : 0.000001
[  274.937402]   .min_vruntime                  : 21440.404455
[  274.937403]   .max_vruntime                  : 0.000001
[  274.937403]   .spread                        : 0.000000
[  274.937404]   .spread0                       : 0.000000
[  274.937405]   .nr_spread_over                : 58
[  274.937406]   .nr_running                    : 0
[  274.937407]   .load                          : 0
[  274.937408]   .load_avg                      : 0.000000
[  274.937409]   .load_period                   : 0.000000
[  274.937409]   .load_contrib                  : 0
[  274.937410]   .load_tg                       : 0
[  274.937411] 
[  274.937412] cfs_rq[0]:/system/rescue.service
[  274.937413]   .exec_clock                    : 4319.868509
[  274.937414]   .MIN_vruntime                  : 0.000001
[  274.937414]   .min_vruntime                  : 4255.887524
[  274.937415]   .max_vruntime                  : 0.000001
[  274.937416]   .spread                        : 0.000000
[  274.937417]   .spread0                       : -17184.516931
[  274.937418]   .nr_spread_over                : 24
[  274.937419]   .nr_running                    : 0
[  274.937420]   .load                          : 0
[  274.937421]   .load_avg                      : 160.004112
[  274.937421]   .load_period                   : 5.496149
[  274.937422]   .load_contrib                  : 29
[  274.937423]   .load_tg                       : 29
[  274.937424]   .se->exec_start                : 272446.423795
[  274.937425]   .se->vruntime                  : 7138.254657
[  274.937426]   .se->sum_exec_runtime          : 4319.796162
[  274.937427]   .se->statistics.wait_start     : 0.000000
[  274.937428]   .se->statistics.sleep_start    : 0.000000
[  274.937429]   .se->statistics.block_start    : 0.000000
[  274.937430]   .se->statistics.sleep_max      : 0.000000
[  274.937431]   .se->statistics.block_max      : 0.000000
[  274.937432]   .se->statistics.exec_max       : 6.213083
[  274.937432]   .se->statistics.slice_max      : 7.894226
[  274.937433]   .se->statistics.wait_max       : 4.870567
[  274.937434]   .se->statistics.wait_sum       : 91.737803
[  274.937435]   .se->statistics.wait_count     : 15504
[  274.937436]   .se->load.weight               : 2
[  274.937437] 
[  274.937438] runnable tasks:
[  274.937438]             task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep
[  274.937439] ----------------------------------------------------------------------------------------------------------
[  274.937444] 
[  274.937445] cpu#1, 2492.220 MHz
[  274.937445]   .nr_running                    : 0
[  274.937446]   .load                          : 0
[  274.937447]   .nr_switches                   : 35274
[  274.937448]   .nr_load_updates               : 105259
[  274.937449]   .nr_uninterruptible            : 1
[  274.937449]   .next_balance                  : 4294.942681
[  274.937450]   .curr->pid                     : 0
[  274.937451]   .clock                         : 274937.357922
[  274.937452]   .cpu_load[0]                   : 0
[  274.937453]   .cpu_load[1]                   : 0
[  274.937454]   .cpu_load[2]                   : 0
[  274.937454]   .cpu_load[3]                   : 0
[  274.937455]   .cpu_load[4]                   : 0
[  274.937456]   .yld_count                     : 0
[  274.937457]   .sched_switch                  : 0
[  274.937458]   .sched_count                   : 35560
[  274.937458]   .sched_goidle                  : 14561
[  274.937459]   .avg_idle                      : 1000000
[  274.937460]   .ttwu_count                    : 16938
[  274.937461]   .ttwu_local                    : 14491
[  274.937462]   .bkl_count                     : 0
[  274.937463] 
[  274.937463] cfs_rq[1]:/
[  274.937464]   .exec_clock                    : 5221.725565
[  274.937465]   .MIN_vruntime                  : 0.000001
[  274.937466]   .min_vruntime                  : 9077.562937
[  274.937466]   .max_vruntime                  : 0.000001
[  274.937467]   .spread                        : 0.000000
[  274.937468]   .spread0                       : -12362.841518
[  274.937469]   .nr_spread_over                : 69
[  274.937470]   .nr_running                    : 0
[  274.937471]   .load                          : 0
[  274.937472]   .load_avg                      : 0.000000
[  274.937473]   .load_period                   : 0.000000
[  274.937473]   .load_contrib                  : 0
[  274.937474]   .load_tg                       : 0
[  274.937475] 
[  274.937476] runnable tasks:
[  274.937476]             task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep
[  274.937477] ----------------------------------------------------------------------------------------------------------
[  274.937481] 
[  274.937482] cpu#2, 2492.220 MHz
[  274.937482]   .nr_running                    : 4
[  274.937483]   .load                          : 2048
[  274.937484]   .nr_switches                   : 7092
[  274.937485]   .nr_load_updates               : 100502
[  274.937486]   .nr_uninterruptible            : 0
[  274.937487]   .next_balance                  : 4294.942806
[  274.937487]   .curr->pid                     : 46
[  274.937488]   .clock                         : 183206.600407
[  274.937489]   .cpu_load[0]                   : 2048
[  274.937490]   .cpu_load[1]                   : 2048
[  274.937491]   .cpu_load[2]                   : 2048
[  274.937492]   .cpu_load[3]                   : 2048
[  274.937492]   .cpu_load[4]                   : 2048
[  274.937493]   .yld_count                     : 0
[  274.937494]   .sched_switch                  : 0
[  274.937495]   .sched_count                   : 7323
[  274.937496]   .sched_goidle                  : 2831
[  274.937496]   .avg_idle                      : 1000000
[  274.937497]   .ttwu_count                    : 3275
[  274.937498]   .ttwu_local                    : 2028
[  274.937499]   .bkl_count                     : 0
[  274.937500] 
[  274.937500] cfs_rq[2]:/
[  274.937501]   .exec_clock                    : 2187.192139
[  274.937502]   .MIN_vruntime                  : 5109.505143
[  274.937503]   .min_vruntime                  : 5118.505143
[  274.937504]   .max_vruntime                  : 5109.505143
[  274.937505]   .spread                        : 0.000000
[  274.937506]   .spread0                       : -16321.899312
[  274.937506]   .nr_spread_over                : 31
[  274.937507]   .nr_running                    : 2
[  274.937508]   .load                          : 2048
[  274.937509]   .load_avg                      : 0.000000
[  274.937510]   .load_period                   : 0.000000
[  274.937511]   .load_contrib                  : 0
[  274.937511]   .load_tg                       : 0
[  274.937512] 
[  274.937513] rt_rq[2]:/
[  274.937513]   .rt_nr_running                 : 1
[  274.937514]   .rt_throttled                  : 0
[  274.937515]   .rt_time                       : 0.000000
[  274.937516]   .rt_runtime                    : 950.000000
[  274.937517] 
[  274.937517] runnable tasks:
[  274.937518]             task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep
[  274.937519] ----------------------------------------------------------------------------------------------------------
[  274.937520]      migration/2    13         0.000000       113     0         0.000000         0.000815         0.000000 /
[  274.937524]       watchdog/2    16         0.000000        17     0         0.000000         1.785026         0.002124 /
[  274.937527] R        kswapd0    46      5118.505143       370   120      5118.505143       415.766870    181943.841718 /
[  274.937531]      kworker/2:1    76      5109.505143      1083   120      5109.505143        25.615305    181183.059001 /
[  274.937535] 
[  274.937536] cpu#3, 2492.220 MHz
[  274.937536]   .nr_running                    : 1
[  274.937537]   .load                          : 1024
[  274.937538]   .nr_switches                   : 22324
[  274.937539]   .nr_load_updates               : 59855
[  274.937540]   .nr_uninterruptible            : 0
[  274.937541]   .next_balance                  : 4294.942679
[  274.937542]   .curr->pid                     : 73
[  274.937543]   .clock                         : 274936.938702
[  274.937543]   .cpu_load[0]                   : 0
[  274.937544]   .cpu_load[1]                   : 0
[  274.937545]   .cpu_load[2]                   : 0
[  274.937546]   .cpu_load[3]                   : 0
[  274.937546]   .cpu_load[4]                   : 0
[  274.937547]   .yld_count                     : 123
[  274.937548]   .sched_switch                  : 0
[  274.937549]   .sched_count                   : 22774
[  274.937550]   .sched_goidle                  : 9952
[  274.937551]   .avg_idle                      : 946660
[  274.937551]   .ttwu_count                    : 10075
[  274.937552]   .ttwu_local                    : 8964
[  274.937553]   .bkl_count                     : 0
[  274.937554] 
[  274.937554] cfs_rq[3]:/
[  274.937555]   .exec_clock                    : 3244.912177
[  274.937556]   .MIN_vruntime                  : 0.000001
[  274.937557]   .min_vruntime                  : 7517.482154
[  274.937558]   .max_vruntime                  : 0.000001
[  274.937559]   .spread                        : 0.000000
[  274.937559]   .spread0                       : -13922.922301
[  274.937560]   .nr_spread_over                : 128
[  274.937561]   .nr_running                    : 1
[  274.937562]   .load                          : 1024
[  274.937563]   .load_avg                      : 0.000000
[  274.937564]   .load_period                   : 0.000000
[  274.937565]   .load_contrib                  : 0
[  274.937565]   .load_tg                       : 0
[  274.937567] 
[  274.937567] runnable tasks:
[  274.937567]             task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep
[  274.937568] ----------------------------------------------------------------------------------------------------------
[  274.937571] R    kworker/3:1    73      7517.482154      7294   120      7517.482154       264.876541    272702.297840 /
[  274.937575] 


--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@...r.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html
Please read the FAQ at  http://www.tux.org/lkml/

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ