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: <20140710144539.GU12478@kvack.org>
Date:	Thu, 10 Jul 2014 10:45:39 -0400
From:	Benjamin LaHaise <bcrl@...ck.org>
To:	"Elliott, Robert (Server Storage)" <Elliott@...com>
Cc:	Jens Axboe <axboe@...nel.dk>,
	Christoph Hellwig <hch@...radead.org>,
	"dgilbert@...erlog.com" <dgilbert@...erlog.com>,
	James Bottomley <James.Bottomley@...senPartnership.com>,
	Bart Van Assche <bvanassche@...ionio.com>,
	"linux-scsi@...r.kernel.org" <linux-scsi@...r.kernel.org>,
	"linux-kernel@...r.kernel.org" <linux-kernel@...r.kernel.org>
Subject: Re: scsi-mq V2

On Thu, Jul 10, 2014 at 02:36:40PM +0000, Elliott, Robert (Server Storage) wrote:
> 
> 
> > -----Original Message-----
> > From: Jens Axboe [mailto:axboe@...nel.dk]
> > Sent: Thursday, 10 July, 2014 8:53 AM
> > To: Christoph Hellwig; Benjamin LaHaise
> > Cc: Elliott, Robert (Server Storage); dgilbert@...erlog.com; James Bottomley;
> > Bart Van Assche; linux-scsi@...r.kernel.org; linux-kernel@...r.kernel.org
> > Subject: Re: scsi-mq V2
> > 
> > On 2014-07-10 15:50, Christoph Hellwig wrote:
> > > On Thu, Jul 10, 2014 at 09:36:09AM -0400, Benjamin LaHaise wrote:
> > >> There is one possible concern that could be exacerbated by other changes
> > in
> > >> the system: if the application is running close to the bare minimum number
> > >> of requests allocated in io_setup(), the per cpu reference counters will
> > >> have a hard time batching things.  It might be worth testing with an
> > >> increased number of requests being allocated if this is the case.
> > >
> > > Well, Robert said reverting the two aio commits didn't help.  Either he
> > > didn't manage to boot into the right kernel, or we need to look
> > > elsewhere for the culprit.
> > 
> > Rob, let me know what scsi_debug setup you use, and I can try and
> > reproduce it here as well.
> > 
> > --
> > Jens Axboe
> 
> This system has 6 online CPUs and 64 possible CPUs.
> 
> Printing avail and req_batch in that loop results in many of these:
> ** 3813 printk messages dropped ** [10643.503772] ctx ffff88042d8d4cc0 avail=0 req_batch=2
> 
> Adding CFLAGS_aio.o := -DDEBUG to the Makefile to enable
> those pr_debug prints results in nothing extra printing,
> so it's not hitting an error.
> 
> Printing nr_events and aio_max_nr at the top of ioctx_alloc results in
> these as fio starts:
> 
> [  186.339064] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.339065] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.339067] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.339068] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.339069] ioctx_alloc: nr_events=-2 aio_max_nr=65536

Something is horribly wrong here.  There is no way that value for nr_events 
should be passed in to ioctx_alloc().  This implies that userland is calling 
io_setup() with an impossibly large value for nr_events.  Can you post the 
actual diff for your fs/aio.c relative to linus' tree?

		-ben


> [  186.339070] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.339071] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.339071] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.339074] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.339076] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.339076] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.359772] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.359971] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.359972] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.359985] sd 5:0:3:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.359986] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.359987] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.359995] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.359995] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.359998] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.359998] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.362529] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.362529] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.363510] sd 5:0:1:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.363513] sd 5:0:4:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.363520] sd 5:0:2:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.363521] sd 5:0:3:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.398113] sd 5:0:5:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.398115] sd 5:0:1:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.398121] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.398122] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.398124] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.398124] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.398130] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.398131] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.398164] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.398165] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.398499] sd 5:0:4:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.400489] sd 5:0:1:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.401478] sd 5:0:1:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.401491] sd 5:0:3:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.434522] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.434523] sd 5:0:3:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.434526] sd 5:0:5:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.434533] sd 5:0:0:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.435370] hrtimer: interrupt took 6868 ns
> [  186.435491] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.435492] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.447864] sd 5:0:0:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.449896] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.449900] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.449901] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.449909] sd 5:0:0:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.449932] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.449933] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.461147] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.461176] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.461177] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.461181] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.461181] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.461184] sd 5:0:2:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.461185] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.461185] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.461191] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.461192] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.474426] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.481353] sd 5:0:1:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.483706] sd 5:0:2:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.483707] sd 5:0:0:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.483709] sd 5:0:4:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.483710] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.483712] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.483717] sd 5:0:2:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.495441] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.495444] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.495445] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.490] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.495451] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.495457] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.495457] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.495460] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.495461] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.495463] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.495464] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.499429] sd 5:0:4:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.499437] sd 5:0:5:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.619785] sd 5:0:4:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.627371] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.627374] sd 5:0:1:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.627383] sd 5:0:3:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.627384] sd 5:0:0:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.627385] sd 5:0:2:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.628371] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.628372] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.630361] sd 5:0:2:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.665329] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.666360] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.666361] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.666366] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.666367] sd 5:0:3:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.666367] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.666369] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [  186.666370] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  186.670369] sd 5:0:5:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.670372] sd 5:0:5:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.670373] sd 5:0:5:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  186.767323] sd 5:0:4:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [  187.211053] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [  187.213053] sd 5:0:0:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> 
> Subsequent added prints showed nr_events coming in with
> an initial value of 0x7FFFFFFF in those cases where it showed
> as -2 above.  Since the last call had a reasonable value
> of 512, it doesn't seem like a problem.
> 
> 
> script to create the scsi_debug devices:
> #!/bin/bash
> devices=6
> delay=0         # -2 = jiffy, -1 = hi jiffy, 0 = none, 1..n = longer
> ndelay=20000    # 20 us
> opts=0x4801     #
> every_nth=3
> capacity_mib=0
> capacity_gib=$((2 * 1024))
> lbpu=1
> lbpws=1
> 
> modprobe -r scsi_debug
> modprobe -v scsi_debug fake_rw=1 delay=$delay ndelay=$ndelay num_tgts=$devices opts=$opts every_nth=$every_nth physblk_exp=3 lbpu=$lbpu lbpws=$lbpws dev_size_mb=$capacity_mib virtual_gb=$capacity_gib
> lsscsi -s
> lsblk
> # the assigned /dev names will vary...
> for device in /sys/block/sda[h-m]
> do
>         echo none > $device/device/queue_type
> done
> 
> fio script:
> [global]
> direct=1
> invalidate=1
> ioengine=libaio
> norandommap
> randrepeat=0
> bs=4096
> iodepth=96
> numjobs=6
> runtime=216000
> time_based=1
> group_reporting
> thread
> gtod_reduce=1
> iodepth_batch=16
> iodepth_batch_complete=16
> cpus_allowed=0-5
> cpus_allowed_policy=split
> rw=randread
> 
> [4_KiB_RR_drive_ah]
> filename=/dev/sdah
> 
> [4_KiB_RR_drive_ai]
> filename=/dev/sdai
> 
> [4_KiB_RR_drive_aj]
> filename=/dev/sdaj
> 
> [4_KiB_RR_drive_ak]
> filename=/dev/sdak
> 
> [4_KiB_RR_drive_al]
> filename=/dev/sdal
> 
> [4_KiB_RR_drive_am]
> filename=/dev/sdam
> 
> kernel log with some prints in ioctx_alloc:
> (2147483647 is 0x7FFFFFFF)
> 
> [   94.050877] ioctx_alloc: initial nr_events=2147483647
> [   94.053610] ioctx_alloc: num_possible_cpus=64
> [   94.055235] ioctx_alloc: after max nr_events=2147483647
> [   94.057110] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.059189] ioctx_alloc: initial nr_events=96
> [   94.059294] ioctx_alloc: initial nr_events=2147483647
> [   94.059295] ioctx_alloc: num_possible_cpus=64
> [   94.059295] ioctx_alloc: after max nr_events=2147483647
> [   94.059296] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.059296] ioctx_alloc: initial nr_events=96
> [   94.059297] ioctx_alloc: num_possible_cpus=64
> [   94.059297] ioctx_alloc: after max nr_events=256
> [   94.059298] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.075891] ioctx_alloc: num_possible_cpus=64
> [   94.077529] ioctx_alloc: after max nr_events=256
> [   94.079064] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.087777] sd 5:0:0:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.087810] ioctx_alloc: initial nr_events=2147483647
> [   94.087810] ioctx_alloc: num_possible_cpus=64
> [   94.087811] ioctx_alloc: after max nr_events=2147483647
> [   94.087811] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.087812] ioctx_alloc: initial nr_events=96
> [   94.087812] ioctx_alloc: num_possible_cpus=64
> [   94.087813] ioctx_alloc: after max nr_events=256
> [   94.087813] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.087815] ioctx_alloc: initial nr_events=2147483647
> [   94.087816] ioctx_alloc: initial nr_events=2147483647
> [   94.087816] ioctx_alloc: num_possible_cpus=64
> [   94.087817] ioctx_alloc: initial nr_events=2147483647
> [   94.087818] ioctx_alloc: num_possible_cpus=64
> [   94.087819] ioctx_alloc: after max nr_events=2147483647
> [   94.087819] ioctx_alloc: num_possible_cpus=64
> [   94.087820] ioctx_alloc: after max nr_events=2147483647
> [   94.087820] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.087821] ioctx_alloc: after max nr_events=2147483647
> [   94.087822] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.087822] ioctx_alloc: initial nr_events=96
> [   94.087823] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.087824] ioctx_alloc: initial nr_events=96
> [   94.087825] ioctx_alloc: initial nr_events=2147483647
> [   94.087825] ioctx_alloc: num_possible_cpus=64
> [   94.087826] ioctx_alloc: initial nr_events=96
> [   94.087826] ioctx_alloc: num_possible_cpus=64
> [   94.087827] ioctx_alloc: num_possible_cpus=64
> [   94.087827] ioctx_alloc: after max nr_events=256
> [   94.087828] ioctx_alloc: num_possible_cpus=64
> [   94.087828] ioctx_alloc: after max nr_events=256
> [   94.087829] ioctx_alloc: after max nr_events=2147483647
> [   94.087829] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.087830] ioctx_alloc: after max nr_events=256
> [   94.087831] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.087831] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.087832] ioctx_alloc: nr_events=512 aio_max_nr=65
> [   94.087833] ioctx_alloc: initial nr_events=96
> [   94.087833] ioctx_alloc: num_possible_cpus=64
> [   94.087833] ioctx_alloc: after max nr_events=256
> [   94.087834] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.090668] sd 5:0:0:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.259433] ioctx_alloc: initial nr_events=2147483647
> [   94.259435] ioctx_alloc: initial nr_events=2147483647
> [   94.259436] ioctx_alloc: num_possible_cpus=64
> [   94.259437] ioctx_alloc: after max nr_events=2147483647
> [   94.259437] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.259438] ioctx_alloc: initial nr_events=96
> [   94.259438] ioctx_alloc: num_possible_cpus=64
> [   94.259438] ioctx_alloc: after max nr_events=256
> [   94.259439] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.259446] ioctx_alloc: initial nr_events=2147483647
> [   94.259448] sd 5:0:2:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.259449] ioctx_alloc: initial nr_events=2147483647
> [   94.259450] ioctx_alloc: initial nr_events=2147483647
> [   94.259450] ioctx_alloc: num_possible_cpus=64
> [   94.259451] ioctx_alloc: num_possible_cpus=64
> [   94.259452] ioctx_alloc: num_possible_cpus=64
> [   94.259452] ioctx_alloc: after max nr_events=2147483647
> [   94.259453] ioctx_alloc: after max nr_events=2147483647
> [   94.259453] ioctx_alloc: after max nr_events=2147483647
> [   94.259454] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.259455] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.259455] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.259456] ioctx_alloc: initial nr_events=96
> [   94.259456] ioctx_alloc: initial nr_events=96
> [   94.259457] ioctx_alloc: initial nr_events=96
> [   94.259457] ioctx_alloc: num_possible_cpus=64
> [   94.259458] ioctx_alloc: num_possible_cpus=64
> [   94.259458] ioctx_alloc: num_possible_cpus=64
> [   94.259459] ioctx_alloc: after max nr_events=256
> [   94.259459] ioctx_alloc: after max nr_events=256
> [   94.259460] ioctx_alloc: after max nr_events=256
> [   94.259460] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   259461] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.259462] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.260539] sd 5:0:5:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.260544] sd 5:0:4:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.262535] sd 5:0:2:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.262550] sd 5:0:3:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.423889] ioctx_alloc: num_possible_cpus=64
> [   94.425386] ioctx_alloc: after max nr_events=2147483647
> [   94.427327] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.429359] ioctx_alloc: initial nr_events=96
> [   94.429448] sd 5:0:3:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.429451] ioctx_alloc: initial nr_events=2147483647
> [   94.429452] sd 5:0:0:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.429453] ioctx_alloc: num_possible_cpus=64
> [   94.429454] ioctx_alloc: initial nr_events=2147483647
> [   94.429454] ioctx_alloc: after max nr_events=2147483647
> [   94.429455] ioctx_alloc: num_possible_cpus=64
> [   94.429456] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.429456] ioctx_alloc: after max nr_events=2147483647
> [   94.429457] ioctx_alloc: initial nr_events=96
> [   94.429458] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.429458] ioctx_alloc: num_possible_cpus=64
> [   94.429459] ioctx_alloc: initial nr_events=96
> [   94.429459] ioctx_alloc: after max nr_events=256
> [   94.429460] ioctx_alloc: num_possible_cpus=64
> [   94.429461] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.429461] ioctx_alloc: after max nr_events=256
> [   94.429462] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.429463] sd 5:0:0:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.430422] hrtimer: interrupt took 6115 ns
> [   94.431463] ioctx_alloc: initial nr_events=2147483647
> [   94.431464] ioctx_alloc: num_possible_cpus=64
> [   94.431464] ioctx_alloc: after max nr_events=2147483647
> [   94.431465] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.431465] ioctx_alloc: initial nr_events=96
> [   94.431466] ioctx_alloc: num_possible_cpus=64
> [   931466] ioctx_alloc: after max nr_events=256
> [   94.431466] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.432641] sd 5:0:2:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.580307] ioctx_alloc: num_possible_cpus=64
> [   94.581844] ioctx_alloc: after max nr_events=256
> [   94.583405] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.585313] sd 5:0:5:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.585319] ioctx_alloc: initial nr_events=2147483647
> [   94.585320] ioctx_alloc: num_possible_cpus=64
> [   94.585320] ioctx_alloc: after max nr_events=2147483647
> [   94.585321] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.585322] ioctx_alloc: initial nr_events=2147483647
> [   94.585322] ioctx_alloc: initial nr_events=96
> [   94.585323] ioctx_alloc: num_possible_cpus=64
> [   94.585324] ioctx_alloc: num_possible_cpus=64
> [   94.585324] ioctx_alloc: after max nr_events=2147483647
> [   94.585325] ioctx_alloc: after max nr_events=256
> [   94.585325] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.585326] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.585327] ioctx_alloc: initial nr_events=2147483647
> [   94.585328] ioctx_alloc: initial nr_events=96
> [   94.585328] ioctx_alloc: num_possible_cpus=64
> [   94.585329] ioctx_alloc: num_possible_cpus=64
> [   94.585329] ioctx_alloc: after max nr_events=2147483647
> [   94.585330] ioctx_alloc: after max nr_events=256
> [   94.585331] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.585331] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.585332] ioctx_alloc: initial nr_events=96
> [   94.585332] ioctx_alloc: num_possible_cpus=64
> [   94.585333] ioctx_alloc: after max nr_events=256
> [   94.585333] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.585372] sd 5:0:5:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.585402] sd 5:0:5:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.588377] sd 5:0:2:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.632221] ioctx_alloc: initial nr_events=2147483647
> [   94.632228] ioctx_alloc: initial nr_events=2147483647
> [   94.632229] iocalloc: num_possible_cpus=64
> [   94.632229] ioctx_alloc: after max nr_events=2147483647
> [   94.632230] ioctx_alloc: initial nr_events=2147483647
> [   94.632231] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.632232] ioctx_alloc: num_possible_cpus=64
> [   94.632232] ioctx_alloc: initial nr_events=96
> [   94.632233] ioctx_alloc: after max nr_events=2147483647
> [   94.632233] ioctx_alloc: num_possible_cpus=64
> [   94.632234] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.632234] ioctx_alloc: after max nr_events=256
> [   94.632235] ioctx_alloc: initial nr_events=96
> [   94.632236] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.632236] ioctx_alloc: num_possible_cpus=64
> [   94.632237] ioctx_alloc: after max nr_events=256
> [   94.632237] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.632241] sd 5:0:0:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.633350] sd 5:0:3:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.764384] ioctx_alloc: num_possible_cpus=64
> [   94.766038] ioctx_alloc: after max nr_events=2147483647
> [   94.767807] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.769568] sd 5:0:5:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.770328] sd 5:0:0:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.773546] ioctx_alloc: initial nr_events=2147483647
> [   94.773550] ioctx_alloc: initial nr_events=2147483647
> [   94.773551] sd 5:0:1:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.773552] ioctx_alloc: num_possible_cpus=64
> [   94.773552] ioctx_alloc: after max nr_events=2147483647
> [   94.773553] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.773553] ioctx_alloc: initial nr_events=96
> [   94.773554] ioctx_alloc: num_possible_cpus=64
> [   94.773554] ioctx_alloc: after max nr_events=256
> [   94.773555] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.773569] ioctx_alloc: initial nr_events=2147483647
> [   94.773569] ioctx_alloc: num_possible_cpus=64
> [   94.773570] ioctx_alloc: after max nr_events=2147483647
> [   94.773570] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.773571] ioctx_alloc:itial nr_events=96
> [   94.773571] ioctx_alloc: num_possible_cpus=64
> [   94.773572] ioctx_alloc: after max nr_events=256
> [   94.773572] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.903978] ioctx_alloc: num_possible_cpus=64
> [   94.905427] ioctx_alloc: after max nr_events=2147483647
> [   94.907320] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.909300] sd 5:0:3:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.909305] ioctx_alloc: initial nr_events=2147483647
> [   94.909306] ioctx_alloc: num_possible_cpus=64
> [   94.909306] ioctx_alloc: after max nr_events=2147483647
> [   94.909307] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.909307] ioctx_alloc: initial nr_events=96
> [   94.909308] ioctx_alloc: num_possible_cpus=64
> [   94.909308] ioctx_alloc: after max nr_events=256
> [   94.909309] ioctx_alloc: initial nr_events=2147483647
> [   94.909310] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.909310] ioctx_alloc: num_possible_cpus=64
> [   94.909311] ioctx_alloc: after max nr_events=2147483647
> [   94.909311] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.909312] ioctx_alloc: initial nr_events=96
> [   94.909312] ioctx_alloc: num_possible_cpus=64
> [   94.909313] ioctx_alloc: after max nr_events=256
> [   94.909313] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.912223] sd 5:0:1:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.940281] sd 5:0:4:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   94.940283] ioctx_alloc: initial nr_events=2147483647
> [   94.940284] ioctx_alloc: num_possible_cpus=64
> [   94.940285] ioctx_alloc: after max nr_events=2147483647
> [   94.940286] ioctx_alloc: initial nr_events=2147483647
> [   94.940286] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.940287] ioctx_alloc: num_possible_cpus=64
> [   94.940288] ioctx_alloc: initial nr_events=96
> [   94.940288] ioctx_alloc: after max nr_events=2147483647
> [   94.940289] ioctx_alloc: num_possible_cpus=64
> [   94.940290] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   94.940290] ioctx_alloc: after max nr_events=256
> [   94.940291] ioctx_alloc: initial nr_events=96
> [   94.940291] ioctx_alloc: nr_events=512 amax_nr=65536
> [   94.940292] ioctx_alloc: num_possible_cpus=64
> [   94.940292] ioctx_alloc: after max nr_events=256
> [   94.940293] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   94.942198] sd 5:0:4:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   95.069096] ioctx_alloc: initial nr_events=96
> [   95.069097] ioctx_alloc: num_possible_cpus=64
> [   95.069097] ioctx_alloc: after max nr_events=256
> [   95.069098] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   95.087101] ioctx_alloc: initial nr_events=2147483647
> [   95.087108] ioctx_alloc: initial nr_events=2147483647
> [   95.087108] ioctx_alloc: num_possible_cpus=64
> [   95.087109] ioctx_alloc: after max nr_events=2147483647
> [   95.087109] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   95.087110] ioctx_alloc: initial nr_events=96
> [   95.087110] ioctx_alloc: num_possible_cpus=64
> [   95.087111] ioctx_alloc: after max nr_events=256
> [   95.087112] ioctx_alloc: initial nr_events=2147483647
> [   95.087113] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   95.087113] ioctx_alloc: num_possible_cpus=64
> [   95.087114] ioctx_alloc: after max nr_events=2147483647
> [   95.087114] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   95.087115] ioctx_alloc: initial nr_events=96
> [   95.087115] ioctx_alloc: num_possible_cpus=64
> [   95.087116] ioctx_alloc: after max nr_events=256
> [   95.087117] sd 5:0:1:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   95.087117] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   95.087120] ioctx_alloc: initial nr_events=2147483647
> [   95.087120] ioctx_alloc: num_possible_cpus=64
> [   95.087121] ioctx_alloc: after max nr_events=2147483647
> [   95.087121] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   95.087122] ioctx_alloc: initial nr_events=96
> [   95.087122] ioctx_alloc: num_possible_cpus=64
> [   95.087123] ioctx_alloc: after max nr_events=256
> [   95.087123] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   95.087126] sd 5:0:4:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   95.091100] ioctx_alloc: initial nr_events=2147483647
> [   95.091100] ioctx_alloc: num_possible_cpus=64
> [   95.091100] ioctx_alloc: after max nr_events=2147483647
> [   95.091101] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   95.091101] ioctx_alloc: initial nr_events=96
> [   95.091102] ioctx_alloc: num_possible_cpus=64
> [   95.091102] ioctx_alloc: after max nr_events=256
> [   95.091103] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   95.145236] ioctx_alloc: num_possible_cpus=64
> [   95.146754] ioctx_alloc: after max nr_events=2483647
> [   95.248567] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   95.250432] ioctx_alloc: initial nr_events=2147483647
> [   95.250438] ioctx_alloc: initial nr_events=2147483647
> [   95.250439] ioctx_alloc: num_possible_cpus=64
> [   95.250439] ioctx_alloc: after max nr_events=2147483647
> [   95.250440] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   95.250440] ioctx_alloc: initial nr_events=96
> [   95.250441] ioctx_alloc: num_possible_cpus=64
> [   95.250441] ioctx_alloc: after max nr_events=256
> [   95.250442] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   95.250450] sd 5:0:5:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   95.250457] sd 5:0:2:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   95.251027] sd 5:0:1:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   95.251038] sd 5:0:1:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   95.252029] sd 5:0:3:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   95.275430] ioctx_alloc: num_possible_cpus=64
> [   95.277000] ioctx_alloc: after max nr_events=2147483647
> [   95.278747] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   95.280540] ioctx_alloc: initial nr_events=2147483647
> [   95.280554] sd 5:0:4:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   95.284457] ioctx_alloc: num_possible_cpus=64
> [   95.285998] ioctx_alloc: after max nr_events=2147483647
> [   95.287764] ioctx_alloc: nr_events=-2 aio_max_nr=65536
> [   95.289455] ioctx_alloc: initial nr_events=96
> [   95.290901] ioctx_alloc: num_possible_cpus=64
> [   95.292450] ioctx_alloc: after max nr_events=256
> [   95.294013] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   95.295873] sd 5:0:3:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   95.381941] ioctx_alloc: initial nr_events=96
> [   95.383764] ioctx_alloc: num_possible_cpus=64
> [   95.385303] ioctx_alloc: after max nr_events=256
> [   95.386959] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   95.391935] ioctx_alloc: initial nr_events=96
> [   95.393493] ioctx_alloc: num_possible_cpus=64
> [   95.394994] ioctx_alloc: after max nr_events=256
> [   95.396751] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   95.421964] sd 5:0:2:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   95.425953] sd 5:0:4:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> [   95.611825] ioctx_alloc: initial nr_events=96
> [   95.613398] ioctx_alloc: num_possible_cpus=64
> [   95.614893] ioctx_alloc: after max nr_events=256
> [   95.616615] ioctx_alloc: nr_events=512 aio_max_nr=65536
> [   95.645844] sd 5:0:1:0: scsi_debug_ioctl: BLKFLSBUF [0x1261]
> 

-- 
"Thought is the essence of where you are now."
--
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