[vfs] WARNING: CPU: 3 PID: 2339 at mm/truncate.c:758 pagecache_isize_extended+0xdd/0x120()

[Fengguang Wu <fengguang.wu@xxxxxxxxx>]

Hi Jan,

Your patch gives a warning on the xfs code path. :)

git://git.kernel.org/pub/scm/linux/kernel/git/tytso/ext4.git dev
commit be330474e2d0533a7a6185e567f3654fec096dbd ("vfs: fix data corruption when 
blocksize < pagesize for mmaped data")


testbox/testcase/testparams: 
bay/fileio/performance-600s-100%-1HDD-xfs-64G-1024f-seqrewr-sync

f6e63f90809946d4  be330474e2d0533a7a6185e567  
----------------  --------------------------  
       fail:runs  %reproduction    fail:runs
           |             |             |    
           :1          100%           5:5     
kmsg.WARNING:at_mm/truncate.c:pagecache_isize_extended()
           :1          100%           5:5     
dmesg.WARNING:at_mm/truncate.c:pagecache_isize_extended()
         %stddev     %change         %stddev
             \          |                \  
      4272 Â 29%    +224.1%      13848 Â 20%  sched_debug.cfs_rq[2]:/.spread0
         5 Â 25%     +84.0%          9 Â 18%  sched_debug.cpu#1.cpu_load[1]
         3 Â 22%     +88.9%          6 Â 21%  sched_debug.cpu#1.cpu_load[2]
         6 Â 23%     +53.1%          9 Â 16%  sched_debug.cpu#1.cpu_load[0]
     15780 Â  5%     +50.5%      23751 Â  8%  sched_debug.cfs_rq[3]:/.exec_clock
     20615 Â  6%     +52.2%      31371 Â 15%  
sched_debug.cfs_rq[3]:/.min_vruntime
    193118 Â 16%     +33.9%     258650 Â 15%  sched_debug.cpu#1.ttwu_local
    277966 Â 10%     -26.1%     205411 Â 13%  sched_debug.cpu#2.ttwu_local
     24040 Â  6%     +38.2%      33230 Â 11%  
sched_debug.cfs_rq[2]:/.min_vruntime
        98 Â 15%     +57.8%        154 Â 23%  
sched_debug.cfs_rq[1]:/.blocked_load_avg
     23851 Â  0%     +33.9%      31941 Â  0%  proc-vmstat.nr_free_pages
     95193 Â  0%     +34.0%     127532 Â  0%  meminfo.MemFree
     96061 Â  0%     +33.4%     128133 Â  0%  vmstat.memory.free
     19715 Â  3%     +27.7%      25185 Â  5%  sched_debug.cfs_rq[2]:/.exec_clock
    690451 Â  9%     -18.2%     564810 Â  6%  sched_debug.cpu#2.ttwu_count
       640 Â 12%     +27.3%        815 Â  9%  
slabinfo.proc_inode_cache.active_objs
       704 Â  6%     +21.3%        854 Â  9%  slabinfo.proc_inode_cache.num_objs
     52372 Â  6%     +18.8%      62197 Â  4%  sched_debug.cpu#3.nr_load_updates
     32780 Â  0%     +18.7%      38924 Â  4%  meminfo.DirectMap4k
      1252 Â  2%     +20.8%       1512 Â  9%  slabinfo.kmalloc-128.num_objs
      1252 Â  2%     +20.8%       1512 Â  9%  slabinfo.kmalloc-128.active_objs
     89552 Â  0%      +9.1%      97715 Â  1%  softirqs.TIMER
     51602 Â  2%     +10.4%      56947 Â  7%  sched_debug.cpu#1.nr_load_updates
     81.08 Â  1%     +14.8%      93.05 Â  1%  time.system_time
      8413 Â  1%      -9.1%       7649 Â  6%  time.involuntary_context_switches
        14 Â  0%     +10.0%         15 Â  3%  time.percent_of_cpu_this_job_got
     14850 Â  0%      -8.1%      13644 Â 11%  vmstat.system.cs
      7715 Â  0%      -7.7%       7118 Â 11%  vmstat.system.in
       603 Â  0%      +2.4%        617 Â  0%  time.elapsed_time
       141 Â  0%      -2.2%        138 Â  0%  iostat.sda.avgqu-sz


<5>[   25.956576] XFS (sda1): Mounting V4 Filesystem
<6>[   26.194468] XFS (sda1): Ending clean mount
<4>[   27.258450] ------------[ cut here ]------------
<4>[   27.258789] WARNING: CPU: 3 PID: 2339 at mm/truncate.c:758 
pagecache_isize_extended+0xdd/0x120()
<4>[   27.259443] Modules linked in: ipmi_watchdog ipmi_msghandler btrfs xor 
raid6_pq sg sr_mod cdrom sd_mod firewire_ohci firewire_core crc_itu_t 
snd_hda_codec_realtek pcspkr snd_hda_codec_generic ahci libahci libata 
snd_hda_intel i2c_i801 snd_hda_controller parport_pc parport snd_hda_codec 
snd_hwdep snd_pcm snd_timer shpchp snd x38_edac edac_core soundcore acpi_cpufreq
<4>[   27.262734] CPU: 3 PID: 2339 Comm: fallocate Not tainted 3.17.0-gda9a9f1 
#1
<4>[   27.263153] Hardware name:                  /        , BIOS 
VVRBLI9J.86A.2891.2007.0511.1144 05/11/2007
<4>[   27.263780]  0000000000000009 ffff88007a43fd88 ffffffff81859ea6 
0000000000000000
<4>[   27.264492]  ffff88007a43fdc0 ffffffff8106ef0d 0000000000001000 
ffff88005b6f05a8
<4>[   27.265199]  0000000000000000 ffff88005b6f05a8 0000000004000000 
ffff88007a43fdd0
<4>[   27.265906] Call Trace:
<4>[   27.266165]  [<ffffffff81859ea6>] dump_stack+0x4d/0x66
<4>[   27.266511]  [<ffffffff8106ef0d>] warn_slowpath_common+0x7d/0xa0
<4>[   27.266900]  [<ffffffff8106efea>] warn_slowpath_null+0x1a/0x20
<4>[   27.267286]  [<ffffffff8117020d>] pagecache_isize_extended+0xdd/0x120
<4>[   27.267690]  [<ffffffff811712b7>] truncate_setsize+0x27/0x40
<4>[   27.268068]  [<ffffffff8133eab7>] xfs_setattr_size+0x157/0x3a0
<4>[   27.268442]  [<ffffffff8134c827>] ? xfs_trans_commit+0x157/0x250
<4>[   27.268821]  [<ffffffff813336df>] xfs_file_fallocate+0x2df/0x300
<4>[   27.269215]  [<ffffffff811dbb09>] ? __sb_start_write+0x49/0xf0
<4>[   27.269596]  [<ffffffff813923b4>] ? selinux_file_permission+0xc4/0x120
<4>[   27.270009]  [<ffffffff811d7563>] do_fallocate+0x123/0x1b0
<4>[   27.270380]  [<ffffffff811d7633>] SyS_fallocate+0x43/0x70
<4>[   27.270738]  [<ffffffff81862c69>] system_call_fastpath+0x16/0x1b
<4>[   27.271119] ---[ end trace 6a3b1350ad399610 ]---
<4>[   27.274498] ------------[ cut here ]------------


Thanks,
Fengguang

dmesg
Description: Text document