kernel-packages team mailing list archive

Thread
Date
[Bug 1250828] Re: freeze on diskIO without real IO

To: kernel-packages@xxxxxxxxxxxxxxxxxxx
From: jl451 <jl451@xxxxxxxxx>
Date: Wed, 13 Nov 2013 13:50:17 -0000
Reply-to: Bug 1250828 <1250828@xxxxxxxxxxxxxxxxxx>
Sender: bounces@xxxxxxxxxxxxx
** Changed in: linux (Ubuntu)
       Status: Incomplete => Confirmed

-- 
You received this bug notification because you are a member of Kernel
Packages, which is subscribed to linux in Ubuntu.
https://bugs.launchpad.net/bugs/1250828

Title:
  freeze on diskIO without real IO

Status in “linux” package in Ubuntu:
  Confirmed

Bug description:
  I have ubuntu 13.04-13.10 with kernel 3.8-3.12 (from distributive and from http://kernel.ubuntu.com/~kernel-ppa/mainline/ )
  Sometimes (in 1-3-5-... minutes) then some program use disk i has freeze it program, and if different program use disk in it time - it may freeze too. But htop and iotop show 0 diskIO at this time. Cpu-usage ~1-5%.

  Now uname -a :
  Linux Ubunto4ka 3.11.7-031107-generic #201311040853 SMP Mon Nov 4 13:54:11 UTC 2013 x86_64 x86_64 x86_64 GNU/Linux

  cat /proc/version
  Linux version 3.11.7-031107-generic (apw@gomeisa) (gcc version 4.6.3 (Ubuntu/Linaro 4.6.3-1ubuntu5) ) #201311040853 SMP Mon Nov 4 13:54:11 UTC 2013

  apt-cache policy linux-image-3.11.7-031107-generic
   *** 3.11.7-031107.201311040853
  (from http://kernel.ubuntu.com/~kernel-ppa/mainline/v3.11.7-saucy/ )

  cat /proc/mounts |grep btrf
  /dev/sda1 / btrfs rw,noatime,compress=zlib,space_cache 0 0
  /dev/sda5 /data btrfs rw,noatime,compress=zlib,space_cache 0 0
  /dev/sda5 /home btrfs rw,noatime,compress=zlib,space_cache 0 0

  btrfs --version
  Btrfs v0.20-rc1

  apt-cache policy btrfs-tools
   *** 0.19+20130705-1 0
          500 http://mirror.yandex.ru/ubuntu/ saucy/main amd64 Packages

  Some logs from  dmesg -Tx :

  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972570] INFO: task okular:28758 blocked for more than 120 seconds.
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972570] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972571] okular          D 0000000000000003     0 28758   1772 0x00000000
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972573]  ffff880094b37948 0000000000000046 ffff880136f32198 ffff880137378800
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972575]  ffff880094b37fd8 ffff880094b37fd8 ffff880094b37fd8 0000000000014540
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972576]  ffff880139370000 ffff88002a814650 ffff880094b37958 ffff880138be0000
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972578] Call Trace:
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972580]  [<ffffffff8171ec99>] schedule+0x29/0x70
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972588]  [<ffffffffa006c6f1>] wait_current_trans.isra.31+0xc1/0x120 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972590]  [<ffffffff81089b00>] ? add_wait_queue+0x60/0x60
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972598]  [<ffffffffa006e53e>] start_transaction.part.33+0x2de/0x500 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972605]  [<ffffffffa006e789>] start_transaction+0x29/0x30 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972612]  [<ffffffffa006e9db>] btrfs_start_transaction+0x1b/0x20 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972620]  [<ffffffffa0079067>] btrfs_setsize.isra.56+0x57/0x1b0 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972628]  [<ffffffffa0079292>] btrfs_setattr+0xd2/0xf0 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972630]  [<ffffffff811cecb3>] notify_change+0x1d3/0x390
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972632]  [<ffffffff812b8da4>] ? truncate_upper.isra.16+0x134/0x230
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972634]  [<ffffffff812b8ff8>] ecryptfs_setattr+0x158/0x320
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972644]  [<ffffffff811cecb3>] notify_change+0x1d3/0x390
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972646]  [<ffffffff811b147e>] do_truncate+0x5e/0xa0
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972648]  [<ffffffff811be2eb>] handle_truncate+0x6b/0xa0
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972650]  [<ffffffff811c2c12>] do_last+0x5d2/0x770
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972652]  [<ffffffff811c2e74>] path_openat+0xc4/0x4f0
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972654]  [<ffffffff811c3653>] ? user_path_at_empty+0x63/0xa0
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972657]  [<ffffffff811c36f3>] do_filp_open+0x43/0xa0
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972659]  [<ffffffff811d02a5>] ? __alloc_fd+0xd5/0x160
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972661]  [<ffffffff811b26f2>] do_sys_open+0x132/0x220
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972663]  [<ffffffff811b27fe>] SyS_open+0x1e/0x20
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972664]  [<ffffffff8172951d>] system_call_fastpath+0x1a/0x1f

  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972286] INFO: task enlightenment:1720 blocked for more than 120 seconds.
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972289] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972291] enlightenment   D 0000000000000000     0  1720   1715 0x00000000
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972294]  ffff8801106679e8 0000000000000046 ffff880136f32198 ffff880137378800
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972296]  ffff880110667fd8 ffff880110667fd8 ffff880110667fd8 0000000000014540
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972298]  ffffffff81c14440 ffff880110668000 ffff8801106679f8 ffff880138be0000
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972301] Call Trace:
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972307]  [<ffffffff8171ec99>] schedule+0x29/0x70
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972328]  [<ffffffffa006c6f1>] wait_current_trans.isra.31+0xc1/0x120 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972332]  [<ffffffff81089b00>] ? add_wait_queue+0x60/0x60
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972341]  [<ffffffffa006e53e>] start_transaction.part.33+0x2de/0x500 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972349]  [<ffffffffa006e789>] start_transaction+0x29/0x30 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972357]  [<ffffffffa006e9db>] btrfs_start_transaction+0x1b/0x20 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972366]  [<ffffffffa007c936>] btrfs_create+0x46/0x220 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972369]  [<ffffffff812ef5bc>] ? security_inode_permission+0x1c/0x30
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972372]  [<ffffffff811c2405>] vfs_create+0xb5/0x120
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972374]  [<ffffffff812b8787>] ecryptfs_do_create+0x57/0x130
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972376]  [<ffffffff812b9ccb>] ecryptfs_create+0x2b/0xc0
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972378]  [<ffffffff811c2405>] vfs_create+0xb5/0x120
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972380]  [<ffffffff811c25ae>] lookup_open+0x13e/0x1d0
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972383]  [<ffffffff811bf1d8>] ? inode_permission+0x18/0x50
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972385]  [<ffffffff811d2af8>] ? __mnt_want_write+0x58/0x70
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972387]  [<ffffffff811c2947>] do_last+0x307/0x770
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972397]  [<ffffffff811c2e74>] path_openat+0xc4/0x4f0
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972399]  [<ffffffff811c36f3>] do_filp_open+0x43/0xa0
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972401]  [<ffffffff811d02a5>] ? __alloc_fd+0xd5/0x160
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972404]  [<ffffffff811b26f2>] do_sys_open+0x132/0x220
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972405]  [<ffffffff811c771e>] ? SyS_select+0xce/0x100
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972407]  [<ffffffff811b27fe>] SyS_open+0x1e/0x20
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972410]  [<ffffffff8172951d>] system_call_fastpath+0x1a/0x1f
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972417] INFO: task BrowserBlocking:3147 blocked for more than 120 seconds.
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972418] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972419] BrowserBlocking D 0000000000000000     0  3147   1720 0x00000000
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972421]  ffff880054d2fd88 0000000000000046 ffffea00010eee40 ffffea00001fb700
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972422]  ffff880054d2ffd8 ffff880054d2ffd8 ffff880054d2ffd8 0000000000014540
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972424]  ffffffff81c14440 ffff88004c6dc650 ffff880054d2fd98 ffff880138be0000
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972426] Call Trace:
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972428]  [<ffffffff8171ec99>] schedule+0x29/0x70
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972436]  [<ffffffffa006c6f1>] wait_current_trans.isra.31+0xc1/0x120 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972438]  [<ffffffff81089b00>] ? add_wait_queue+0x60/0x60
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972445]  [<ffffffffa006e53e>] start_transaction.part.33+0x2de/0x500 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972453]  [<ffffffffa0070928>] ? btrfs_writepages+0x28/0x30 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972460]  [<ffffffffa006e789>] start_transaction+0x29/0x30 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972467]  [<ffffffffa006e9db>] btrfs_start_transaction+0x1b/0x20 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972476]  [<ffffffffa007ef4d>] btrfs_sync_file+0x17d/0x2b0 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972479]  [<ffffffff811e25fb>] vfs_fsync+0x2b/0x40
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972481]  [<ffffffff812b808d>] ecryptfs_fsync+0x3d/0x50
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972482]  [<ffffffff811e26dd>] do_fsync+0x5d/0x90
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972484]  [<ffffffff811b3da0>] ? SyS_read+0x70/0xa0
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972486]  [<ffffffff811e2ae3>] SyS_fdatasync+0x13/0x20
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972488]  [<ffffffff8172951d>] system_call_fastpath+0x1a/0x1f
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972490] INFO: task BrowserBlocking:3150 blocked for more than 120 seconds.
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972490] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972491] BrowserBlocking D 0000000000000002     0  3150   1720 0x00000000
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972493]  ffff88004c375d28 0000000000000046 ffff88004c375cd8 ffffffffa0087de2
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972495]  ffff88004c375fd8 ffff88004c375fd8 ffff88004c375fd8 0000000000014540
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972496]  ffff88013935ddc0 ffff88004c655dc0 ffff88004c375d38 ffff880137378800
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972498] Call Trace:
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972507]  [<ffffffffa0087de2>] ? free_extent_buffer.part.40+0x32/0x90 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972509]  [<ffffffff8171ec99>] schedule+0x29/0x70
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972518]  [<ffffffffa00a41fc>] wait_log_commit.isra.22+0x10c/0x130 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972520]  [<ffffffff81089b00>] ? add_wait_queue+0x60/0x60
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972529]  [<ffffffffa00a9c72>] btrfs_sync_log+0x442/0x6d0 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972531]  [<ffffffff811c9e39>] ? dput+0x49/0x220
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972539]  [<ffffffffa007f055>] btrfs_sync_file+0x285/0x2b0 [btrfs]
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972541]  [<ffffffff811e25fb>] vfs_fsync+0x2b/0x40
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972543]  [<ffffffff812b808d>] ecryptfs_fsync+0x3d/0x50
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972545]  [<ffffffff811e26dd>] do_fsync+0x5d/0x90
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972546]  [<ffffffff811b3f76>] ? SyS_pwrite64+0x66/0xa0
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972548]  [<ffffffff811e2ac0>] SyS_fsync+0x10/0x20
  Nov  8 17:27:52 Ubunto4ka kernel: [177440.972550]  [<ffffffff8172951d>] system_call_fastpath+0x1a/0x1f
  --- 
  ApportVersion: 2.12.5-0ubuntu2.1
  Architecture: amd64
  DistroRelease: Ubuntu 13.10
  EcryptfsInUse: Yes
  MarkForUpload: True
  NonfreeKernelModules: nvidia
  Package: linux (not installed)
  Tags:  saucy
  Uname: Linux 3.11.7-031107-generic x86_64
  UnreportableReason: The running kernel is not an Ubuntu kernel
  UpgradeStatus: No upgrade log present (probably fresh install)
  UserGroups: adm audio cdrom dialout dip lpadmin netdev plugdev sambashare sudo

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1250828/+subscriptions
References

[Bug 1250828] [NEW] freeze on diskIO without real IO
From: jl451, 2013-11-13