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: <alpine.LRH.2.11.1507172204220.13702@math.ut.ee>
Date:	Fri, 17 Jul 2015 22:07:34 +0300 (EEST)
From:	mroos@...ux.ee
To:	Thomas Graf <tgraf@...g.ch>
cc:	davem@...emloft.net, netdev@...r.kernel.org,
	herbert@...dor.apana.org.au, eric.dumazet@...il.com
Subject: Re: [PATCH net-next v2] rhashtable: Allow other tasks to be scheduled
 in large lookup loops

> Depending on system speed, the large lookup/insert/delete loops of the testsuite can
> take a considerable amount of time to complete causing watchdog warnings to appear.
> Allow other tasks to be scheduled throughout the loops.
> 
> Reported-by: Meelis Roos <mroos@...ux.ee>
> Signed-off-by: Thomas Graf <tgraf@...g.ch>
> ---
> v2: Use cond_resched() instead schedule()

Tested it. The warning is gone from rhashtable test but now it is 
present in rbtree test (it was not there before). Same kernel, just your 
patch applied - but it should not change rbtree test???

[    0.000000] PROMLIB: Sun IEEE Boot Prom 'OBP 3.31.0 2001/07/25 20:36'
[    0.000000] PROMLIB: Root node compatible: 
[    0.000000] Linux version 4.2.0-rc2-00077-gf760b87-dirty (mroos@u5) (gcc version 4.9.3 (Debian 4.9.3-1) ) #21 Fri Jul 17 20:15:21 EEST 2015
[    0.000000] bootconsole [earlyprom0] enabled
[    0.000000] ARCH: SUN4U
[    0.000000] Ethernet address: 08:00:20:f8:c7:72
[    0.000000] MM: PAGE_OFFSET is 0xfffff80000000000 (max_phys_bits == 40)
[    0.000000] MM: VMALLOC [0x0000000100000000 --> 0x0000060000000000]
[    0.000000] MM: VMEMMAP [0x0000060000000000 --> 0x00000c0000000000]
[    0.000000] Kernel: Using 10 locked TLB entries for main kernel image.
[    0.000000] Remapping the kernel... done.
[    0.000000] kmemleak: Kernel memory leak detector disabled
[    0.000000] OF stdout device is: /pci@1f,0/pci@1,1/ebus@...e@14,400000:a
[    0.000000] PROM: Built device tree with 70266 bytes of memory.
[    0.000000] Top of RAM: 0x1ff2c000, Total RAM: 0x1ff2a000
[    0.000000] Memory hole size: 0MB
[    0.000000] Allocated 16384 bytes for kernel page tables.
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x000000001ff2bfff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000001fefdfff]
[    0.000000]   node   0: [mem 0x000000001ff00000-0x000000001ff2bfff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000001ff2bfff]
[    0.000000] On node 0 totalpages: 65429
[    0.000000]   Normal zone: 512 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 65429 pages, LIFO batch:15
[    0.000000] Booting Linux...
[    0.000000] CPU CAPS: [flush,stbar,swap,muldiv,v9,mul32,div32,v8plus]
[    0.000000] CPU CAPS: [vis]
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 64917
[    0.000000] Kernel command line: root=/dev/sda1 ro
[    0.000000] PID hash table entries: 2048 (order: 1, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 262144 bytes)
[    0.000000] Sorting __ex_table...
[    0.000000] Memory: 475912K/523432K available (5270K kernel code, 516K rwdata, 1672K rodata, 520K init, 30210K bss, 47520K reserved, 0K cma-reserved)
[    0.000000] Running RCU self tests
[    0.000000] Testing tracer nop: PASSED
[    0.000000] NR_IRQS:2048 nr_irqs:2048 1
[   26.882478] clocksource: tick: mask: 0xffffffffffffffff max_cycles: 0x5306eb473f, max_idle_ns: 440795213232 ns
[   26.986192] clocksource: mult[2c71c72] shift[24]
[   27.025729] clockevent: mult[5c28f5c3] shift[32]
[   27.067997] Console: colour dummy device 80x25
[   27.104149] console [tty0] enabled
[   27.128868] bootconsole [earlyprom0] disabled
[   27.165340] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[   27.165405] ... MAX_LOCKDEP_SUBCLASSES:  8
[   27.165445] ... MAX_LOCK_DEPTH:          48
[   27.165486] ... MAX_LOCKDEP_KEYS:        8191
[   27.165529] ... CLASSHASH_SIZE:          4096
[   27.165574] ... MAX_LOCKDEP_ENTRIES:     32768
[   27.165617] ... MAX_LOCKDEP_CHAINS:      65536
[   27.165662] ... CHAINHASH_SIZE:          32768
[   27.165706]  memory used by lock dependency info: 8159 kB
[   27.165756]  per task-struct memory footprint: 1920 bytes
[   27.165802] ------------------------
[   27.165838] | Locking API testsuite:
[   27.165873] ----------------------------------------------------------------------------
[   27.165932]                                  | spin |wlock |rlock |mutex | wsem | rsem |
[   27.165993]   --------------------------------------------------------------------------
[   27.166092]                      A-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[   27.232682]                  A-B-B-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[   27.299789]              A-B-B-C-C-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[   27.367295]              A-B-C-A-B-C deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[   27.434877]          A-B-B-C-C-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[   27.502857]          A-B-C-D-B-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[   27.570913]          A-B-C-D-B-C-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[   27.639030]                     double unlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[   27.706053]                   initialize held:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[   27.772899]                  bad unlock order:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[   27.840393]   --------------------------------------------------------------------------
[   27.840491]               recursive read-lock:             |  ok  |             |  ok  |
[   27.862994]            recursive read-lock #2:             |  ok  |             |  ok  |
[   27.885492]             mixed read-write-lock:             |  ok  |             |  ok  |
[   27.908000]             mixed write-read-lock:             |  ok  |             |  ok  |
[   27.930496]   --------------------------------------------------------------------------
[   27.930594]      hard-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[   27.964173]      soft-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[   27.997791]      hard-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[   28.031406]      soft-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[   28.065035]        sirq-safe-A => hirqs-on/12:  ok  |  ok  |  ok  |
[   28.098675]        sirq-safe-A => hirqs-on/21:  ok  |  ok  |  ok  |
[   28.132309]          hard-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[   28.165911]          soft-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[   28.199528]          hard-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[   28.233145]          soft-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[   28.266773]     hard-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[   28.300558]     soft-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[   28.334375]     hard-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[   28.368163]     soft-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[   28.401968]     hard-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[   28.435786]     soft-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[   28.469610]     hard-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[   28.503385]     soft-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[   28.537166]     hard-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[   28.570842]     soft-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[   28.604517]     hard-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[   28.638286]     soft-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[   28.672064]     hard-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[   28.705863]     soft-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[   28.739714]     hard-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[   28.773532]     soft-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[   28.807368]     hard-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[   28.841217]     soft-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[   28.875100]     hard-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[   28.908929]     soft-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[   28.942758]     hard-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[   28.976574]     soft-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[   29.010499]     hard-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[   29.044316]     soft-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[   29.078152]       hard-irq lock-inversion/123:  ok  |  ok  |  ok  |
[   29.112000]       soft-irq lock-inversion/123:  ok  |  ok  |  ok  |
[   29.145894]       hard-irq lock-inversion/132:  ok  |  ok  |  ok  |
[   29.179746]       soft-irq lock-inversion/132:  ok  |  ok  |  ok  |
[   29.213631]       hard-irq lock-inversion/213:  ok  |  ok  |  ok  |
[   29.247510]       soft-irq lock-inversion/213:  ok  |  ok  |  ok  |
[   29.281418]       hard-irq lock-inversion/231:  ok  |  ok  |  ok  |
[   29.315262]       soft-irq lock-inversion/231:  ok  |  ok  |  ok  |
[   29.349134]       hard-irq lock-inversion/312:  ok  |  ok  |  ok  |
[   29.382994]       soft-irq lock-inversion/312:  ok  |  ok  |  ok  |
[   29.416906]       hard-irq lock-inversion/321:  ok  |  ok  |  ok  |
[   29.450756]       soft-irq lock-inversion/321:  ok  |  ok  |  ok  |
[   29.484624]       hard-irq read-recursion/123:  ok  |
[   29.495986]       soft-irq read-recursion/123:  ok  |
[   29.507378]       hard-irq read-recursion/132:  ok  |
[   29.518743]       soft-irq read-recursion/132:  ok  |
[   29.530133]       hard-irq read-recursion/213:  ok  |
[   29.541494]       soft-irq read-recursion/213:  ok  |
[   29.552870]       hard-irq read-recursion/231:  ok  |
[   29.564247]       soft-irq read-recursion/231:  ok  |
[   29.575630]       hard-irq read-recursion/312:  ok  |
[   29.587004]       soft-irq read-recursion/312:  ok  |
[   29.598397]       hard-irq read-recursion/321:  ok  |
[   29.609776]       soft-irq read-recursion/321:  ok  |
[   29.621160]   --------------------------------------------------------------------------
[   29.621249]   | Wound/wait tests |
[   29.621306]   ---------------------
[   29.621371]                   ww api failures:  ok  |  ok  |  ok  |
[   29.655302]                ww contexts mixing:  ok  |  ok  |
[   29.677841]              finishing ww context:  ok  |  ok  |  ok  |  ok  |
[   29.722821]                locking mismatches:  ok  |  ok  |  ok  |
[   29.756630]                  EDEADLK handling:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[   29.869817]            spinlock nest unlocked:  ok  |
[   29.881116]   -----------------------------------------------------
[   29.881193]                                  |block | try  |context|
[   29.881267]   -----------------------------------------------------
[   29.881346]                           context:  ok  |  ok  |  ok  |
[   29.915341]                               try:  ok  |  ok  |  ok  |
[   29.949163]                             block:  ok  |  ok  |  ok  |
[   29.982955]                          spinlock:  ok  |  ok  |  ok  |
[   30.017142] -------------------------------------------------------
[   30.017239] Good, all 253 testcases passed! |
[   30.017306] ---------------------------------
[   30.026148] ODEBUG: selftest passed
[   30.026520] kmemleak: Early log buffer exceeded (2042), please increase DEBUG_KMEMLEAK_EARLY_LOG_SIZE
[   30.167716] Calibrating delay using timer specific routine.. 727.30 BogoMIPS (lpj=3636502)
[   30.167862] pid_max: default: 32768 minimum: 301
[   30.169425] Mount-cache hash table entries: 1024 (order: 0, 8192 bytes)
[   30.169565] Mountpoint-cache hash table entries: 1024 (order: 0, 8192 bytes)
[   30.177641] ftrace: allocating 15171 entries in 30 pages
[   30.257853] devtmpfs: initialized
[   30.301402] Performance events: No support for PMU type 'ultra12'
[   30.305277] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[   30.308770] Testing tracer function: 
[   30.311509] kworker/u2:0 (11) used greatest stack depth: 8168 bytes left
[   30.468249] PASSED
[   30.468339] Testing dynamic ftrace: PASSED
[   30.748319] Testing dynamic ftrace ops #1: (1 0 1 0 0) (1 1 2 0 0) (2 1 3 0 73) (2 2 4 0 161) PASSED
[   31.038108] Testing dynamic ftrace ops #2: (1 0 1 77 0) (1 1 2 165 0) (2 1 3 1 3) (2 2 4 86 88) PASSED
[   31.335881] Testing ftrace recursion: PASSED
[   31.402866] Testing ftrace recursion safe: PASSED
[   31.470396] Testing ftrace regs(no arch support): PASSED
[   31.537829] Testing tracer irqsoff: PASSED
[   31.563853] Testing tracer function_graph: PASSED
[   31.733617] atomic64_test: passed
[   31.739606] NET: Registered protocol family 16
[   31.812981] /pci@1f,0: PCI IO[1fe02000000] MEM[1ff00000000]
[   31.813239] /pci@1f,0: SABRE PCI Bus Module ver[0:0]
[   31.813337] PCI: Scanning PBM /pci@1f,0
[   31.816979] sabre f005f9c0: PCI host bridge to bus 0000:00
[   31.817127] pci_bus 0000:00: root bus resource [io  0x1fe02000000-0x1fe02ffffff] (bus address [0x0000-0xffffff])
[   31.817291] pci_bus 0000:00: root bus resource [mem 0x1ff00000000-0x1ffffffffff] (bus address [0x00000000-0xffffffff])
[   31.817466] pci_bus 0000:00: root bus resource [bus 00-02]
[   31.843473] pci 0000:00:01.0: can't claim BAR 8 [mem 0x1ff00000000-0x1ffbfffffff]: address conflict with Video RAM area [??? 0x1ff000a0000-0x1ff000bffff flags 0x80000000]
[   31.921366] kworker/u2:1 (20) used greatest stack depth: 7896 bytes left
[   32.212004] vgaarb: device added: PCI:0000:01:02.0,decodes=io+mem,owns=none,locks=none
[   32.212179] vgaarb: loaded
[   32.212240] vgaarb: bridge control possible 0000:01:02.0
[   32.215255] SCSI subsystem initialized
[   32.227747] libata version 3.00 loaded.
[   32.241232] /pci@1f,0/pci@1,1/ebus@...eprom@14,0: Mostek regs at 0x1fff1000000
[   32.446414] DMA-API: preallocated 32768 debug entries
[   32.446532] DMA-API: debugging enabled by kernel config
[   32.447868] AUXIO: Found device at /pci@1f,0/pci@1,1/ebus@...uxio@14,726000
[   32.449072] clocksource: Switched to clocksource tick
[   33.122262] NET: Registered protocol family 2
[   33.128387] TCP established hash table entries: 4096 (order: 2, 32768 bytes)
[   33.128769] TCP bind hash table entries: 4096 (order: 5, 262144 bytes)
[   33.132231] TCP: Hash tables configured (established 4096 bind 4096)
[   33.133737] UDP hash table entries: 256 (order: 2, 40960 bytes)
[   33.134394] UDP-Lite hash table entries: 256 (order: 2, 40960 bytes)
[   33.137508] NET: Registered protocol family 1
[   33.138194] PCI: CLS 64 bytes, default 64
[   33.140569] power: Control reg at 1fff1724000
[   33.164530] futex hash table entries: 256 (order: 1, 20480 bytes)
[   33.165052] Initialise system trusted keyring
[   33.166138] ====[ backtrace testing ]===========
[   33.166211] Testing a backtrace from process context.
[   33.166275] The following trace is a kernel self test and not a bug!
[   33.166367] CPU: 0 PID: 1 Comm: swapper Not tainted 4.2.0-rc2-00077-gf760b87-dirty #21
[   33.166453] Call Trace:
[   33.166534]  [00000000004d2870] backtrace_regression_test+0x30/0x100
[   33.166640]  [0000000000426e2c] do_one_initcall+0xec/0x1e0
[   33.166743]  [0000000000b58b60] kernel_init_freeable+0x114/0x1c4
[   33.166859]  [000000000091c1ec] kernel_init+0xc/0x100
[   33.166968]  [0000000000405fe4] ret_from_fork+0x1c/0x2c
[   33.167053]  [0000000000000000]           (null)
[   33.167124] Testing a backtrace from irq context.
[   33.167186] The following trace is a kernel self test and not a bug!
[   33.169165] CPU: 0 PID: 10 Comm: kworker/u2:1 Not tainted 4.2.0-rc2-00077-gf760b87-dirty #21
[   33.169307] Workqueue: khelper __call_usermodehelper
[   33.169399] Call Trace:
[   33.169494]  [00000000004d280c] backtrace_test_irq_callback+0xc/0x40
[   33.169602]  [0000000000460118] tasklet_action+0x78/0xe0
[   33.169697]  [000000000045f378] __do_softirq+0x278/0x680
[   33.169802]  [000000000042be28] do_softirq_own_stack+0x28/0x40
[   33.169900]  [000000000045fa6c] irq_exit+0x4c/0xc0
[   33.170007]  [0000000000929910] timer_interrupt+0x70/0xa0
[   33.170104]  [0000000000426b34] sys_call_table+0x5b8/0x744
[   33.170206]  [00000000006f352c] clear_page_common+0x54/0x80
[   33.170310]  [000000000052bca8] __alloc_pages_nodemask+0x148/0xa60
[   33.170410]  [000000000052c8fc] alloc_kmem_pages_node+0x1c/0x40
[   33.170511]  [0000000000458cd4] copy_process.isra.47+0xf4/0x1580
[   33.170608]  [000000000045a28c] _do_fork+0x6c/0x7c0
[   33.170699]  [000000000045aa48] kernel_thread+0x28/0x40
[   33.170799]  [000000000047171c] __call_usermodehelper+0x3c/0x80
[   33.170899]  [000000000047639c] process_one_work+0x3dc/0x800
[   33.170995]  [0000000000476ad0] worker_thread+0x310/0x500
[   33.186175] Testing a saved backtrace.
[   33.186268] The following trace is a kernel self test and not a bug!
[   33.186346]  [<00000000004d290c>] backtrace_regression_test+0xcc/0x100
[   33.186497]  [<0000000000426e2c>] do_one_initcall+0xec/0x1e0
[   33.186614]  [<0000000000b58b60>] kernel_init_freeable+0x114/0x1c4
[   33.186736]  [<000000000091c1ec>] kernel_init+0xc/0x100
[   33.186855]  [<0000000000405fe4>] ret_from_fork+0x1c/0x2c
[   33.186974]  [<          (null)>]           (null)
[   33.187061] ====[ end of backtrace testing ]====
[   33.187292] audit: initializing netlink subsys (disabled)
[   33.188439] audit: type=2000 audit(5.630:1): initialized
[   33.203418] HugeTLB registered 8 MB page size, pre-allocated 0 pages
[   33.216884] Key type big_key registered
[   33.253244] io scheduler noop registered
[   33.253690] io scheduler cfq registered (default)
[   33.253830] start plist test
[   33.277753] end plist test
[   33.277825] test_string_helpers: Running tests...
[   33.284353] Running rhashtable test nelem=8, max_size=65536, shrinking=0
[   33.284453] Test 00:
[   33.393788]   Adding 50000 keys
[   35.520471]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   36.463930]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   36.464060]   Deleting 50000 keys
[   37.299798]   Duration of test: 3906469241 ns
[   37.301050] Test 01:
[   37.410517]   Adding 50000 keys
[   39.539627]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   40.483583]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   40.483714]   Deleting 50000 keys
[   41.318696]   Duration of test: 3908637840 ns
[   41.319906] Test 02:
[   41.429373]   Adding 50000 keys
[   43.555317]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   44.498058]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   44.498188]   Deleting 50000 keys
[   45.332522]   Duration of test: 3903606299 ns
[   45.333733] Test 03:
[   45.443216]   Adding 50000 keys
[   47.569233]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   48.511179]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   48.511309]   Deleting 50000 keys
[   49.346186]   Duration of test: 3903425713 ns
[   49.347555] Average test time: 3905534773
[   49.348144] rbtree testing -> 74807 cycles
[   70.333187] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper:1]
[   70.333401] Modules linked in:
[   70.333499] irq event stamp: 2018938
[   70.333584] hardirqs last  enabled at (2018937): [<0000000000404b1c>] rtrap_xcall+0x18/0x20
[   70.333770] hardirqs last disabled at (2018938): [<0000000000426b28>] sys_call_table+0x5ac/0x744
[   70.333967] softirqs last  enabled at (2018936): [<000000000045f5fc>] __do_softirq+0x4fc/0x680
[   70.334168] softirqs last disabled at (2018929): [<000000000042be28>] do_softirq_own_stack+0x28/0x40
[   70.334394] CPU: 0 PID: 1 Comm: swapper Not tainted 4.2.0-rc2-00077-gf760b87-dirty #21
[   70.334499] task: fffff8001f09ef60 ti: fffff8001f0fc000 task.ti: fffff8001f0fc000
[   70.334626] TSTATE: 0000009980001607 TPC: 00000000006e7a3c TNPC: 00000000006e7a40 Y: 00000000    Not tainted
[   70.334811] TPC: <rb_next+0x7c/0xa0>
[   70.334902] g0: 0000000000426b28 g1: 00000000029273d8 g2: 00000000029273d8 g3: 0000000002927310
[   70.335056] g4: fffff8001f09ef60 g5: 0000000000000000 g6: fffff8001f0fc000 g7: 0000000002927310
[   70.335210] o0: 0000000000000001 o1: fffff8001f0fc3f8 o2: 0000000000921a38 o3: 0000000000000000
[   70.335365] o4: 0000000000000000 o5: 0000000000b1c800 sp: fffff8001f0ff231 ret_pc: 0000000000404b1c
[   70.335533] RPC: <rtrap_xcall+0x18/0x20>
[   70.335628] l0: 0000000000001000 l1: 0000000080001606 l2: 0000000000921a34 l3: 0000000000000400
[   70.335754] l4: 0000000000000000 l5: 0000000000000000 l6: 0000000000000000 l7: 0000000000000008
[   70.335879] i0: 0000000002927130 i1: 0000000002927420 i2: 000000052604be08 i3: 0000000582c429c9
[   70.336005] i4: 0000000002926a00 i5: 0000000002926a01 i6: fffff8001f0ff2e1 i7: 0000000000921adc
[   70.336142] I7: <check+0x130/0x2d8>
[   70.336214] Call Trace:
[   70.336301]  [0000000000921adc] check+0x130/0x2d8
[   70.336420]  [0000000000b7cf18] rbtree_test_init+0x124/0x264
[   70.336544]  [0000000000426e2c] do_one_initcall+0xec/0x1e0
[   70.336675]  [0000000000b58b60] kernel_init_freeable+0x114/0x1c4
[   70.336812]  [000000000091c1ec] kernel_init+0xc/0x100
[   70.336939]  [0000000000405fe4] ret_from_fork+0x1c/0x2c
[   70.337045]  [0000000000000000]           (null)
[   70.606757] augmented rbtree testing -> 54275 cycles
[   86.305230] PCI: Enabling device: (0000:01:02.0), cmd 82
[   86.305404] atyfb: 3D RAGE PRO (Mach64 GP, PQFP, PCI) [0x4750 rev 0x7c]
[   86.305551] atyfb: 4M SGRAM (1:1), 14.31818 MHz XTAL, 230 MHz PLL, 100 Mhz MCLK, 100 MHz XCLK
[   86.369992] Console: switching to colour frame buffer device 80x30
[   86.382675] atyfb: fb0: ATY Mach64 frame buffer device on PCI
[   86.411787] f0061c64: ttyS0 at MMIO 0x1fff1400000 (irq = 5, base_baud = 115200) is a SAB82532 V3.2
[   86.420984] Console: ttyS0 (SAB82532)
[  103.575130] console [ttyS0] enabled
[  103.615226] f0061c64: ttyS1 at MMIO 0x1fff1400040 (irq = 5, base_baud = 115200) is a SAB82532 V3.2
[  104.070027] Floppy drive(s): fd0 is 1.44M
[  104.266698] loop: module loaded
[  104.350297] scsi host0: pata_cmd64x
[  104.390818] scsi host1: pata_cmd64x
[  104.423873] ata1: PATA max MWDMA2 cmd 0x1fe02c00000 ctl 0x1fe02c00008 bmdma 0x1fe02c00020 irq 14
[  104.520242] ata2: PATA max MWDMA2 cmd 0x1fe02c00010 ctl 0x1fe02c00018 bmdma 0x1fe02c00028 irq 14
[  104.622786] pata_cmd64x: active 10 recovery 10 setup 3.
[  104.622908] pata_cmd64x: active 10 recovery 10 setup 3.
[  104.637447] FDC 0 is a National Semiconductor PC87306
[  104.688758] mousedev: PS/2 mouse device common for all mice
[  104.770560] rtc-m48t59 rtc-m48t59.0: rtc core: registered m48t59 as rtc0
[  104.844445] NET: Registered protocol family 10
[  104.911225] ata1.00: ATA-6: ST3120026A, 3.06, max UDMA/100
[  104.964706] ata1.00: 234441648 sectors, multi 0: LBA48 
[  105.014998] pata_cmd64x: active 3 recovery 1 setup 1.
[  105.015094] pata_cmd64x: active 3 recovery 1 setup 1.
[  105.042768] NET: Registered protocol family 17
[  105.088267] ata1.00: configured for MWDMA2
[  105.130117] scsi 0:0:0:0: Direct-Access     ATA      ST3120026A       3.06 PQ: 0 ANSI: 5
[  105.231225] sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/111 GiB)
[  105.310038] sd 0:0:0:0: [sda] Write Protect is off
[  105.354869] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[  105.355534] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[  105.464628] pata_cmd64x: active 10 recovery 10 setup 3.
[  105.464706] pata_cmd64x: active 10 recovery 10 setup 3.
[  105.486362] Loading compiled-in X.509 certificates
[  105.531723] registered taskstats version 1
[  105.568193] Running tests on trace events:
[  105.604489] Testing event sys_exit: 
[  105.631832] ata2.00: ATAPI: CRD-8322B, 1.06, max MWDMA2
[  105.686562] pata_cmd64x: active 3 recovery 1 setup 1.
[  105.686657] pata_cmd64x: active 3 recovery 1 setup 1.
[  105.699521] ata2.00: configured for MWDMA2
[  105.739853] OK
[  105.746950] Testing event sys_enter: 
[  105.783288] scsi 1:0:0:0: CD-ROM               LG    CD-ROM CRD-8322B 1.06 PQ: 0 ANSI: 5
[  105.874726]  sda: sda1 sda2 sda3
[  105.909065] OK
[  105.915753] Testing event task_rename: 
[  105.969072] sd 0:0:0:0: [sda] Attached SCSI disk
[  106.020084] OK
[  106.026640] Testing event task_newtask: OK
[  106.085748] Testing event softirq_raise: 
[  106.121495] sr 1:0:0:0: [sr0] scsi3-mmc drive: 32x/32x cd/rw xa/form2 cdda tray
[  106.200669] cdrom: Uniform CD-ROM driver Revision: 3.20
[  106.260064] OK
[  106.266648] Testing event softirq_exit: 
[  106.304243] sr 1:0:0:0: Attached scsi CD-ROM sr0
[  106.320463] OK
[  106.326825] Testing event softirq_entry: OK
[  106.385469] Testing event irq_handler_exit: OK
[  106.445229] Testing event irq_handler_entry: OK
[  106.504686] Testing event signal_deliver: OK
[  106.554947] Testing event signal_generate: OK
[  106.614542] Testing event workqueue_execute_end: OK
[  106.674956] Testing event workqueue_execute_start: OK
[  106.734784] Testing event workqueue_activate_work: OK
[  106.794474] Testing event workqueue_queue_work: OK
[  106.854831] Testing event sched_wake_idle_without_ipi: OK
[  106.924393] Testing event sched_swap_numa: OK
[  106.974592] Testing event sched_stick_numa: OK
[  107.034424] Testing event sched_move_numa: OK
[  107.094169] Testing event sched_process_hang: OK
[  107.154236] Testing event sched_pi_setprio: OK
[  107.214197] Testing event sched_stat_runtime: OK
[  107.274222] Testing event sched_stat_blocked: OK
[  107.334016] Testing event sched_stat_iowait: OK
[  107.393735] Testing event sched_stat_sleep: OK
[  107.454023] Testing event sched_stat_wait: OK
[  107.503736] Testing event sched_process_exec: OK
[  107.563682] Testing event sched_process_fork: OK
[  107.623558] Testing event sched_process_wait: OK
[  107.683573] Testing event sched_wait_task: OK
[  107.733430] Testing event sched_process_exit: OK
[  107.793375] Testing event sched_process_free: OK
[  107.853310] Testing event sched_migrate_task: OK
[  107.913307] Testing event sched_switch: OK
[  107.963075] Testing event sched_wakeup_new: OK
[  108.013278] Testing event sched_wakeup: OK
[  108.063318] Testing event sched_kthread_stop_ret: OK
[  108.123234] Testing event sched_kthread_stop: OK
[  108.183207] Testing event lock_release: OK
[  108.234462] Testing event lock_acquire: OK
[  108.284387] Testing event console: OK
[  108.333092] Testing event rcu_utilization: OK
[  108.382889] Testing event tick_stop: OK
[  108.433211] Testing event itimer_expire: OK
[  108.483094] Testing event itimer_state: OK
[  108.533070] Testing event hrtimer_cancel: OK
[  108.583170] Testing event hrtimer_expire_exit: OK
[  108.643073] Testing event hrtimer_expire_entry: OK
[  108.703160] Testing event hrtimer_start: OK
[  108.753062] Testing event hrtimer_init: OK
[  108.803047] Testing event timer_cancel: OK
[  108.853126] Testing event timer_expire_exit: OK
[  108.913143] Testing event timer_expire_entry: OK
[  108.973140] Testing event timer_start: OK
[  109.023135] Testing event timer_init: OK
[  109.073098] Testing event module_request: OK
[  109.122808] Testing event module_put: OK
[  109.173113] Testing event module_get: OK
[  109.223055] Testing event module_free: OK
[  109.273029] Testing event module_load: OK
[  109.323021] Testing event ftrace_test_filter: OK
[  109.382997] Testing event dev_pm_qos_remove_request: OK
[  109.442735] Testing event dev_pm_qos_update_request: OK
[  109.502988] Testing event dev_pm_qos_add_request: OK
[  109.563219] Testing event pm_qos_update_flags: OK
[  109.623032] Testing event pm_qos_update_target: OK
[  109.682991] Testing event pm_qos_update_request_timeout: OK
[  109.752974] Testing event pm_qos_remove_request: OK
[  109.812963] Testing event pm_qos_update_request: OK
[  109.872941] Testing event pm_qos_add_request: OK
[  109.932932] Testing event power_domain_target: OK
[  109.993140] Testing event clock_set_rate: OK
[  110.042762] Testing event clock_disable: OK
[  110.093046] Testing event clock_enable: OK
[  110.142937] Testing event wakeup_source_deactivate: OK
[  110.202757] Testing event wakeup_source_activate: OK
[  110.263059] Testing event suspend_resume: OK
[  110.312987] Testing event device_pm_callback_end: OK
[  110.373106] Testing event device_pm_callback_start: OK
[  110.433093] Testing event cpu_frequency: OK
[  110.483002] Testing event pstate_sample: OK
[  110.532992] Testing event cpu_idle: OK
[  110.583128] Testing event rpm_return_int: OK
[  110.633151] Testing event rpm_idle: OK
[  110.682855] Testing event rpm_resume: OK
[  110.733126] Testing event rpm_suspend: OK
[  110.783057] Testing event mm_filemap_add_to_page_cache: OK
[  110.853114] Testing event mm_filemap_delete_from_page_cache: OK
[  110.922806] Testing event oom_score_adj_update: OK
[  110.983137] Testing event mm_lru_activate: OK
[  111.033367] Testing event mm_lru_insertion: OK
[  111.092916] Testing event mm_vmscan_lru_shrink_inactive: OK
[  111.163147] Testing event mm_vmscan_writepage: OK
[  111.222974] Testing event mm_vmscan_memcg_isolate: OK
[  111.283267] Testing event mm_vmscan_lru_isolate: OK
[  111.342928] Testing event mm_shrink_slab_end: OK
[  111.403224] Testing event mm_shrink_slab_start: OK
[  111.463306] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK
[  111.543154] Testing event mm_vmscan_memcg_reclaim_end: OK
[  111.613139] Testing event mm_vmscan_direct_reclaim_end: OK
[  111.683139] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK
[  111.763139] Testing event mm_vmscan_memcg_reclaim_begin: OK
[  111.832917] Testing event mm_vmscan_direct_reclaim_begin: OK
[  111.903252] Testing event mm_vmscan_wakeup_kswapd: OK
[  111.962924] Testing event mm_vmscan_kswapd_wake: OK
[  112.023234] Testing event mm_vmscan_kswapd_sleep: OK
[  112.083087] Testing event mm_page_alloc_extfrag: OK
[  112.143189] Testing event mm_page_pcpu_drain: OK
[  112.202869] Testing event mm_page_alloc_zone_locked: OK
[  112.263207] Testing event mm_page_alloc: OK
[  112.313205] Testing event mm_page_free_batched: OK
[  112.373147] Testing event mm_page_free: OK
[  112.422881] Testing event kmem_cache_free: OK
[  112.473131] Testing event kfree: OK
[  112.513005] Testing event kmem_cache_alloc_node: OK
[  112.573023] Testing event kmalloc_node: OK
[  112.622991] Testing event kmem_cache_alloc: OK
[  112.682746] Testing event kmalloc: OK
[  112.733056] Testing event mm_compaction_defer_reset: OK
[  112.792972] Testing event mm_compaction_defer_compaction: OK
[  112.862928] Testing event mm_compaction_deferred: OK
[  112.922937] Testing event mm_compaction_suitable: OK
[  112.982629] Testing event mm_compaction_finished: OK
[  113.042882] Testing event mm_compaction_try_to_compact_pages: OK
[  113.112768] Testing event mm_compaction_end: OK
[  113.172827] Testing event mm_compaction_begin: OK
[  113.232762] Testing event mm_compaction_migratepages: OK
[  113.302834] Testing event mm_compaction_isolate_freepages: OK
[  113.372389] Testing event mm_compaction_isolate_migratepages: OK
[  113.442686] Testing event mm_numa_migrate_ratelimit: OK
[  113.502845] Testing event mm_migrate_pages: OK
[  113.562432] Testing event writeback_dirty_inode_enqueue: OK
[  113.632776] Testing event writeback_lazytime_iput: OK
[  113.692761] Testing event writeback_lazytime: OK
[  113.752733] Testing event writeback_single_inode: OK
[  113.812719] Testing event writeback_single_inode_start: OK
[  113.882483] Testing event writeback_wait_iff_congested: OK
[  113.952775] Testing event writeback_congestion_wait: OK
[  114.012454] Testing event writeback_sb_inodes_requeue: OK
[  114.082742] Testing event balance_dirty_pages: OK
[  114.142648] Testing event bdi_dirty_ratelimit: OK
[  114.202426] Testing event global_dirty_state: OK
[  114.262735] Testing event writeback_queue_io: OK
[  114.322581] Testing event wbc_writepage: OK
[  114.372596] Testing event writeback_bdi_register: OK
[  114.432605] Testing event writeback_wake_background: OK
[  114.492413] Testing event writeback_nowork: OK
[  114.552743] Testing event writeback_pages_written: OK
[  114.612400] Testing event writeback_wait: OK
[  114.662686] Testing event writeback_written: OK
[  114.722678] Testing event writeback_start: OK
[  114.772652] Testing event writeback_exec: OK
[  114.822600] Testing event writeback_queue: OK
[  114.872262] Testing event writeback_write_inode: OK
[  114.932510] Testing event writeback_write_inode_start: OK
[  115.002442] Testing event writeback_dirty_inode: OK
[  115.062386] Testing event writeback_dirty_inode_start: OK
[  115.132132] Testing event writeback_mark_inode_dirty: OK
[  115.202358] Testing event writeback_dirty_page: OK
[  115.262358] Testing event time_out_leases: OK
[  115.312176] Testing event generic_delete_lease: OK
[  115.372495] Testing event generic_add_lease: OK
[  115.432366] Testing event break_lease_unblock: OK
[  115.492363] Testing event break_lease_block: OK
[  115.552087] Testing event break_lease_noblock: OK
[  115.612572] Testing event ext4_es_shrink: OK
[  115.662248] Testing event ext4_insert_range: OK
[  115.722027] Testing event ext4_collapse_range: OK
[  115.782316] Testing event ext4_es_shrink_scan_exit: OK
[  115.842190] Testing event ext4_es_shrink_scan_enter: OK
[  115.902143] Testing event ext4_es_shrink_count: OK
[  115.961910] Testing event ext4_es_lookup_extent_exit: OK
[  116.032014] Testing event ext4_es_lookup_extent_enter: OK
[  116.102181] Testing event ext4_es_find_delayed_extent_range_exit: OK
[  116.182225] Testing event ext4_es_find_delayed_extent_range_enter: OK
[  116.262198] Testing event ext4_es_remove_extent: OK
[  116.322255] Testing event ext4_es_cache_extent: OK
[  116.381947] Testing event ext4_es_insert_extent: OK
[  116.442454] Testing event ext4_ext_remove_space_done: OK
[  116.512268] Testing event ext4_ext_remove_space: OK
[  116.572233] Testing event ext4_ext_rm_idx: OK
[  116.622236] Testing event ext4_ext_rm_leaf: OK
[  116.682181] Testing event ext4_remove_blocks: OK
[  116.741946] Testing event ext4_ext_show_extent: OK
[  116.802124] Testing event ext4_get_reserved_cluster_alloc: OK
[  116.872121] Testing event ext4_find_delalloc_range: OK
[  116.932144] Testing event ext4_ext_in_cache: OK
[  116.991916] Testing event ext4_ext_put_in_cache: OK
[  117.052338] Testing event ext4_get_implied_cluster_alloc_exit: OK
[  117.132178] Testing event ext4_ext_handle_unwritten_extents: OK
[  117.202189] Testing event ext4_trim_all_free: OK
[  117.262223] Testing event ext4_trim_extent: OK
[  117.322190] Testing event ext4_journal_start_reserved: OK
[  117.392286] Testing event ext4_journal_start: OK
[  117.452392] Testing event ext4_load_inode: OK
[  117.502284] Testing event ext4_ext_load_extent: OK
[  117.562264] Testing event ext4_ind_map_blocks_exit: OK
[  117.622298] Testing event ext4_ext_map_blocks_exit: OK
[  117.682195] Testing event ext4_ind_map_blocks_enter: OK
[  117.741884] Testing event ext4_ext_map_blocks_enter: OK
[  117.802092] Testing event ext4_ext_convert_to_initialized_fastpath: OK
[  117.882129] Testing event ext4_ext_convert_to_initialized_enter: OK
[  117.961889] Testing event ext4_truncate_exit: OK
[  118.022123] Testing event ext4_truncate_enter: OK
[  118.082106] Testing event ext4_unlink_exit: OK
[  118.142100] Testing event ext4_unlink_enter: OK
[  118.201872] Testing event ext4_fallocate_exit: OK
[  118.262277] Testing event ext4_zero_range: OK
[  118.311856] Testing event ext4_punch_hole: OK
[  118.362100] Testing event ext4_fallocate_enter: OK
[  118.421952] Testing event ext4_direct_IO_exit: OK
[  118.481921] Testing event ext4_direct_IO_enter: OK
[  118.541991] Testing event ext4_load_inode_bitmap: OK
[  118.601830] Testing event ext4_read_block_bitmap_load: OK
[  118.671570] Testing event ext4_mb_buddy_bitmap_load: OK
[  118.731846] Testing event ext4_mb_bitmap_load: OK
[  118.791754] Testing event ext4_da_release_space: OK
[  118.851514] Testing event ext4_da_reserve_space: OK
[  118.911795] Testing event ext4_da_update_reserve_space: OK
[  118.981790] Testing event ext4_forget: OK
[  119.031797] Testing event ext4_mballoc_free: OK
[  119.091966] Testing event ext4_mballoc_discard: OK
[  119.151564] Testing event ext4_mballoc_prealloc: OK
[  119.211758] Testing event ext4_mballoc_alloc: OK
[  119.271455] Testing event ext4_alloc_da_blocks: OK
[  119.331703] Testing event ext4_sync_fs: OK
[  119.381707] Testing event ext4_sync_file_exit: OK
[  119.441419] Testing event ext4_sync_file_enter: OK
[  119.501617] Testing event ext4_free_blocks: OK
[  119.561500] Testing event ext4_allocate_blocks: OK
[  119.621627] Testing event ext4_request_blocks: OK
[  119.681606] Testing event ext4_mb_discard_preallocations: OK
[  119.751621] Testing event ext4_discard_preallocations: OK
[  119.821659] Testing event ext4_mb_release_group_pa: OK
[  119.881454] Testing event ext4_mb_release_inode_pa: OK
[  119.941614] Testing event ext4_mb_new_group_pa: OK
[  120.001639] Testing event ext4_mb_new_inode_pa: OK
[  120.061587] Testing event ext4_discard_blocks: OK
[  120.121663] Testing event ext4_journalled_invalidatepage: OK
[  120.191658] Testing event ext4_invalidatepage: OK
[  120.251674] Testing event ext4_releasepage: OK
[  120.311235] Testing event ext4_readpage: OK
[  120.361663] Testing event ext4_writepage: OK
[  120.411622] Testing event ext4_writepages_result: OK
[  120.471256] Testing event ext4_da_write_pages_extent: OK
[  120.541577] Testing event ext4_da_write_pages: OK
[  120.601555] Testing event ext4_writepages: OK
[  120.651254] Testing event ext4_da_write_end: OK
[  120.711563] Testing event ext4_journalled_write_end: OK
[  120.771566] Testing event ext4_write_end: OK
[  120.821568] Testing event ext4_da_write_begin: OK
[  120.881567] Testing event ext4_write_begin: OK
[  120.941482] Testing event ext4_begin_ordered_truncate: OK
[  121.011592] Testing event ext4_mark_inode_dirty: OK
[  121.071597] Testing event ext4_drop_inode: OK
[  121.121581] Testing event ext4_evict_inode: OK
[  121.181105] Testing event ext4_allocate_inode: OK
[  121.241577] Testing event ext4_request_inode: OK
[  121.301442] Testing event ext4_free_inode: OK
[  121.351253] Testing event ext4_other_inode_update_time: OK
[  121.421776] Testing event jbd2_lock_buffer_stall: OK
[  121.481529] Testing event jbd2_write_superblock: OK
[  121.541309] Testing event jbd2_update_log_tail: OK
[  121.601584] Testing event jbd2_checkpoint_stats: OK
[  121.661546] Testing event jbd2_run_stats: OK
[  121.711540] Testing event jbd2_handle_stats: OK
[  121.771226] Testing event jbd2_handle_extend: OK
[  121.831515] Testing event jbd2_handle_start: OK
[  121.891183] Testing event jbd2_submit_inode_data: OK
[  121.951475] Testing event jbd2_end_commit: OK
[  122.001486] Testing event jbd2_drop_transaction: OK
[  122.061475] Testing event jbd2_commit_logging: OK
[  122.121477] Testing event jbd2_commit_flushing: OK
[  122.181426] Testing event jbd2_commit_locking: OK
[  122.241376] Testing event jbd2_start_commit: OK
[  122.301567] Testing event jbd2_checkpoint: OK
[  122.351134] Testing event block_rq_remap: OK
[  122.401307] Testing event block_bio_remap: OK
[  122.451048] Testing event block_split: OK
[  122.501445] Testing event block_unplug: OK
[  122.551212] Testing event block_plug: OK
[  122.601282] Testing event block_sleeprq: OK
[  122.650936] Testing event block_getrq: OK
[  122.701428] Testing event block_bio_queue: OK
[  122.751071] Testing event block_bio_frontmerge: OK
[  122.811001] Testing event block_bio_backmerge: OK
[  122.870972] Testing event block_bio_complete: OK
[  122.930943] Testing event block_bio_bounce: OK
[  122.980703] Testing event block_rq_issue: OK
[  123.030989] Testing event block_rq_insert: OK
[  123.080953] Testing event block_rq_complete: OK
[  123.140943] Testing event block_rq_requeue: OK
[  123.200732] Testing event block_rq_abort: OK
[  123.250898] Testing event block_dirty_buffer: OK
[  123.310755] Testing event block_touch_buffer: OK
[  123.370813] Testing event urandom_read: OK
[  123.420807] Testing event random_read: OK
[  123.470594] Testing event extract_entropy_user: OK
[  123.530830] Testing event extract_entropy: OK
[  123.581114] Testing event get_random_bytes_arch: OK
[  123.640603] Testing event get_random_bytes: OK
[  123.690776] Testing event xfer_secondary_pool: OK
[  123.750797] Testing event add_disk_randomness: OK
[  123.810581] Testing event add_input_randomness: OK
[  123.870912] Testing event debit_entropy: OK
[  123.920839] Testing event push_to_pool: OK
[  123.970842] Testing event credit_entropy_bits: OK
[  124.030862] Testing event mix_pool_bytes_nolock: OK
[  124.090878] Testing event mix_pool_bytes: OK
[  124.140653] Testing event add_device_randomness: OK
[  124.200986] Testing event scsi_eh_wakeup: OK
[  124.250900] Testing event scsi_dispatch_cmd_timeout: OK
[  124.310921] Testing event scsi_dispatch_cmd_done: OK
[  124.371006] Testing event scsi_dispatch_cmd_error: OK
[  124.430988] Testing event scsi_dispatch_cmd_start: OK
[  124.490957] Testing event ata_eh_link_autopsy_qc: OK
[  124.550983] Testing event ata_eh_link_autopsy: OK
[  124.611169] Testing event ata_qc_complete_done: OK
[  124.670954] Testing event ata_qc_complete_failed: OK
[  124.730641] Testing event ata_qc_complete_internal: OK
[  124.790917] Testing event ata_qc_issue: OK
[  124.840919] Testing event udp_fail_queue_rcv_skb: OK
[  124.900909] Testing event sock_exceed_buf_limit: OK
[  124.960940] Testing event sock_rcvqueue_full: OK
[  125.021118] Testing event napi_poll: OK
[  125.070941] Testing event netif_rx_ni_entry: OK
[  125.130911] Testing event netif_rx_entry: OK
[  125.180680] Testing event netif_receive_skb_entry: OK
[  125.240980] Testing event napi_gro_receive_entry: OK
[  125.300660] Testing event napi_gro_frags_entry: OK
[  125.360962] Testing event netif_rx: OK
[  125.410848] Testing event netif_receive_skb: OK
[  125.470808] Testing event net_dev_queue: OK
[  125.520818] Testing event net_dev_xmit: OK
[  125.570753] Testing event net_dev_start_xmit: OK
[  125.630710] Testing event skb_copy_datagram_iovec: OK
[  125.690709] Testing event consume_skb: OK
[  125.740525] Testing event kfree_skb: OK
[  125.790844] Running tests on trace event systems:
[  125.832325] Testing event system skb: OK
[  125.882834] Testing event system net: OK
[  125.932068] Testing event system napi: OK
[  125.981285] Testing event system sock: OK
[  126.031262] Testing event system udp: OK
[  126.081239] Testing event system libata: OK
[  126.131616] Testing event system scsi: OK
[  126.181453] Testing event system random: OK
[  126.232440] Testing event system block: OK
[  126.282603] Testing event system jbd2: OK
[  126.332346] Testing event system ext4: OK
[  126.400100] Testing event system filelock: OK
[  126.461122] Testing event system writeback: OK
[  126.523266] Testing event system migrate: OK
[  126.580922] Testing event system compaction: OK
[  126.641635] Testing event system kmem: OK
[  126.691691] Testing event system vmscan: OK
[  126.741880] Testing event system pagemap: OK
[  126.800495] Testing event system oom: OK
[  126.850800] Testing event system filemap: OK
[  126.900778] Testing event system rpm: OK
[  126.950933] Testing event system power: OK
[  127.002532] Testing event system test: OK
[  127.050533] Testing event system module: OK
[  127.100758] Testing event system timer: OK
[  127.151608] Testing event system rcu: OK
[  127.200363] Testing event system printk: OK
[  127.250363] Testing event system lock: OK
[  127.302560] Testing event system sched: OK
[  127.362124] Testing event system workqueue: OK
[  127.420457] Testing event system signal: OK
[  127.470556] Testing event system irq: OK
[  127.520862] Testing event system task: OK
[  127.570531] Testing event system raw_syscalls: OK
[  127.630544] Running tests on all trace events:
[  127.668475] Testing all events: OK
[  127.805073] Running tests again, along with the function tracer
[  127.874088] Running tests on trace events:
[  127.922026] Testing event sys_exit: OK
[  128.058250] Testing event sys_enter: OK
[  128.129245] Testing event task_rename: OK
[  128.207855] Testing event task_newtask: OK
[  128.278416] Testing event softirq_raise: OK
[  128.357587] Testing event softirq_exit: OK
[  128.428613] Testing event softirq_entry: OK
[  128.507441] Testing event irq_handler_exit: OK
[  128.588926] Testing event irq_handler_entry: OK
[  128.668478] Testing event signal_deliver: OK
[  128.748481] Testing event signal_generate: OK
[  128.828439] Testing event workqueue_execute_end: OK
[  128.908786] Testing event workqueue_execute_start: OK
[  128.998243] Testing event workqueue_activate_work: OK
[  129.088956] Testing event workqueue_queue_work: OK
[  129.169894] Testing event sched_wake_idle_without_ipi: OK
[  129.258935] Testing event sched_swap_numa: OK
[  129.338969] Testing event sched_stick_numa: OK
[  129.418392] Testing event sched_move_numa: OK
[  129.498397] Testing event sched_process_hang: OK
[  129.579507] Testing event sched_pi_setprio: OK
[  129.658782] Testing event sched_stat_runtime: OK
[  129.738622] Testing event sched_stat_blocked: OK
[  129.818060] Testing event sched_stat_iowait: OK
[  129.897979] Testing event sched_stat_sleep: OK
[  129.978193] Testing event sched_stat_wait: OK
[  130.057222] Testing event sched_process_exec: OK
[  130.137818] Testing event sched_process_fork: OK
[  130.217727] Testing event sched_process_wait: OK
[  130.297669] Testing event sched_wait_task: OK
[  130.378027] Testing event sched_process_exit: OK
[  130.458034] Testing event sched_process_free: OK
[  130.537890] Testing event sched_migrate_task: OK
[  130.617633] Testing event sched_switch: OK
[  130.686638] Testing event sched_wakeup_new: OK
[  130.766834] Testing event sched_wakeup: OK
[  130.846578] Testing event sched_kthread_stop_ret: OK
[  130.927341] Testing event sched_kthread_stop: OK
[  131.007719] Testing event lock_release: OK
[  131.087529] Testing event lock_acquire: OK
[  131.166804] Testing event console: OK
[  131.236970] Testing event rcu_utilization: OK
[  131.316633] Testing event tick_stop: OK
[  131.387459] Testing event itimer_expire: OK
[  131.467406] Testing event itimer_state: OK
[  131.537207] Testing event hrtimer_cancel: OK
[  131.617683] Testing event hrtimer_expire_exit: OK
[  131.697045] Testing event hrtimer_expire_entry: OK
[  131.777297] Testing event hrtimer_start: OK
[  131.856263] Testing event hrtimer_init: OK
[  131.927544] Testing event timer_cancel: OK
[  131.997159] Testing event timer_expire_exit: OK
[  132.077289] Testing event timer_expire_entry: OK
[  132.157508] Testing event timer_start: OK
[  132.227874] Testing event timer_init: OK
[  132.297540] Testing event module_request: OK
[  132.377317] Testing event module_put: OK
[  132.448082] Testing event module_get: OK
[  132.517088] Testing event module_free: OK
[  132.587139] Testing event module_load: OK
[  132.656873] Testing event ftrace_test_filter: OK
[  132.737037] Testing event dev_pm_qos_remove_request: OK
[  132.827559] Testing event dev_pm_qos_update_request: OK
[  132.917427] Testing event dev_pm_qos_add_request: 
[  132.987016] test-events (821) used greatest stack depth: 7256 bytes left
[  133.110909] OK
[  133.129162] Testing event pm_qos_update_flags: OK
[  133.208344] Testing event pm_qos_update_target: OK
[  133.287509] Testing event pm_qos_update_request_timeout: OK
[  133.378204] Testing event pm_qos_remove_request: OK
[  133.467659] Testing event pm_qos_update_request: OK
[  133.548532] Testing event pm_qos_add_request: OK
[  133.637703] Testing event power_domain_target: OK
[  133.718855] Testing event clock_set_rate: OK
[  133.798407] Testing event clock_disable: OK
[  133.878284] Testing event clock_enable: OK
[  133.956939] Testing event wakeup_source_deactivate: OK
[  134.048744] Testing event wakeup_source_activate: OK
[  134.138593] Testing event suspend_resume: OK
[  134.219307] Testing event device_pm_callback_end: OK
[  134.307959] Testing event device_pm_callback_start: OK
[  134.398501] Testing event cpu_frequency: OK
[  134.478365] Testing event pstate_sample: OK
[  134.558565] Testing event cpu_idle: OK
[  134.628803] Testing event rpm_return_int: OK
[  134.708782] Testing event rpm_idle: OK
[  134.778463] Testing event rpm_resume: OK
[  134.857837] Testing event rpm_suspend: OK
[  134.928202] Testing event mm_filemap_add_to_page_cache: OK
[  135.018383] Testing event mm_filemap_delete_from_page_cache: OK
[  135.118156] Testing event oom_score_adj_update: OK
[  135.197781] Testing event mm_lru_activate: OK
[  135.277850] Testing event mm_lru_insertion: OK
[  135.357678] Testing event mm_vmscan_lru_shrink_inactive: OK
[  135.448556] Testing event mm_vmscan_writepage: OK
[  135.527975] Testing event mm_vmscan_memcg_isolate: OK
[  135.617617] Testing event mm_vmscan_lru_isolate: OK
[  135.706914] Testing event mm_shrink_slab_end: OK
[  135.787685] Testing event mm_shrink_slab_start: OK
[  135.867918] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK
[  135.967245] Testing event mm_vmscan_memcg_reclaim_end: OK
[  136.057780] Testing event mm_vmscan_direct_reclaim_end: OK
[  136.147694] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK
[  136.247126] Testing event mm_vmscan_memcg_reclaim_begin: OK
[  136.337559] Testing event mm_vmscan_direct_reclaim_begin: OK
[  136.427710] Testing event mm_vmscan_wakeup_kswapd: OK
[  136.517638] Testing event mm_vmscan_kswapd_wake: OK
[  136.607376] Testing event mm_vmscan_kswapd_sleep: OK
[  136.697003] Testing event mm_page_alloc_extfrag: OK
[  136.787082] Testing event mm_page_pcpu_drain: OK
[  136.867919] Testing event mm_page_alloc_zone_locked: OK
[  136.958042] Testing event mm_page_alloc: OK
[  137.037803] Testing event mm_page_free_batched: OK
[  137.117960] Testing event mm_page_free: OK
[  137.197531] Testing event kmem_cache_free: OK
[  137.277661] Testing event kfree: OK
[  137.347894] Testing event kmem_cache_alloc_node: OK
[  137.436772] Testing event kmalloc_node: OK
[  137.507099] Testing event kmem_cache_alloc: OK
[  137.587019] Testing event kmalloc: OK
[  137.657623] Testing event mm_compaction_defer_reset: OK
[  137.747873] Testing event mm_compaction_defer_compaction: OK
[  137.846706] Testing event mm_compaction_deferred: OK
[  137.936466] Testing event mm_compaction_suitable: OK
[  138.026565] Testing event mm_compaction_finished: OK
[  138.115932] Testing event mm_compaction_try_to_compact_pages: OK
[  138.217217] Testing event mm_compaction_end: OK
[  138.296322] Testing event mm_compaction_begin: OK
[  138.377292] Testing event mm_compaction_migratepages: OK
[  138.467255] Testing event mm_compaction_isolate_freepages: OK
[  138.565943] Testing event mm_compaction_isolate_migratepages: OK
[  138.667334] Testing event mm_numa_migrate_ratelimit: OK
[  138.756878] Testing event mm_migrate_pages: OK
[  138.838058] Testing event writeback_dirty_inode_enqueue: OK
[  138.927333] Testing event writeback_lazytime_iput: OK
[  139.017570] Testing event writeback_lazytime: OK
[  139.097324] Testing event writeback_single_inode: OK
[  139.186003] Testing event writeback_single_inode_start: OK
[  139.277378] Testing event writeback_wait_iff_congested: OK
[  139.367039] Testing event writeback_congestion_wait: OK
[  139.457932] Testing event writeback_sb_inodes_requeue: OK
[  139.546764] Testing event balance_dirty_pages: OK
[  139.636260] Testing event bdi_dirty_ratelimit: OK
[  139.717306] Testing event global_dirty_state: OK
[  139.797048] Testing event writeback_queue_io: OK
[  139.876774] Testing event wbc_writepage: OK
[  139.955784] Testing event writeback_bdi_register: OK
[  140.036642] Testing event writeback_wake_background: OK
[  140.127242] Testing event writeback_nowork: OK
[  140.206321] Testing event writeback_pages_written: OK
[  140.296964] Testing event writeback_wait: OK
[  140.376958] Testing event writeback_written: OK
[  140.457395] Testing event writeback_start: OK
[  140.537255] Testing event writeback_exec: OK
[  140.616552] Testing event writeback_queue: OK
[  140.696990] Testing event writeback_write_inode: OK
[  140.785301] Testing event writeback_write_inode_start: OK
[  140.877506] Testing event writeback_dirty_inode: OK
[  140.965499] Testing event writeback_dirty_inode_start: OK
[  141.057258] Testing event writeback_mark_inode_dirty: OK
[  141.146897] Testing event writeback_dirty_page: OK
[  141.226441] Testing event time_out_leases: OK
[  141.306798] Testing event generic_delete_lease: OK
[  141.387003] Testing event generic_add_lease: OK
[  141.467220] Testing event break_lease_unblock: OK
[  141.547396] Testing event break_lease_block: OK
[  141.626714] Testing event break_lease_noblock: OK
[  141.706538] Testing event ext4_es_shrink: OK
[  141.786888] Testing event ext4_insert_range: OK
[  141.867101] Testing event ext4_collapse_range: OK
[  141.946960] Testing event ext4_es_shrink_scan_exit: OK
[  142.036557] Testing event ext4_es_shrink_scan_enter: OK
[  142.126531] Testing event ext4_es_shrink_count: OK
[  142.206625] Testing event ext4_es_lookup_extent_exit: OK
[  142.297462] Testing event ext4_es_lookup_extent_enter: OK
[  142.386427] Testing event ext4_es_find_delayed_extent_range_exit: OK
[  142.486948] Testing event ext4_es_find_delayed_extent_range_enter: OK
[  142.595818] Testing event ext4_es_remove_extent: OK
[  142.676612] Testing event ext4_es_cache_extent: OK
[  142.765873] Testing event ext4_es_insert_extent: OK
[  142.846492] Testing event ext4_ext_remove_space_done: OK
[  142.937316] Testing event ext4_ext_remove_space: OK
[  143.025381] Testing event ext4_ext_rm_idx: OK
[  143.106594] Testing event ext4_ext_rm_leaf: OK
[  143.185694] Testing event ext4_remove_blocks: OK
[  143.266043] Testing event ext4_ext_show_extent: OK
[  143.345955] Testing event ext4_get_reserved_cluster_alloc: OK
[  143.445491] Testing event ext4_find_delalloc_range: OK
[  143.536006] Testing event ext4_ext_in_cache: OK
[  143.615942] Testing event ext4_ext_put_in_cache: OK
[  143.696011] Testing event ext4_get_implied_cluster_alloc_exit: OK
[  143.796279] Testing event ext4_ext_handle_unwritten_extents: OK
[  143.896394] Testing event ext4_trim_all_free: OK
[  143.976675] Testing event ext4_trim_extent: OK
[  144.056487] Testing event ext4_journal_start_reserved: OK
[  144.146400] Testing event ext4_journal_start: OK
[  144.226187] Testing event ext4_load_inode: OK
[  144.306374] Testing event ext4_ext_load_extent: OK
[  144.386859] Testing event ext4_ind_map_blocks_exit: OK
[  144.476714] Testing event ext4_ext_map_blocks_exit: OK
[  144.566445] Testing event ext4_ind_map_blocks_enter: OK
[  144.656294] Testing event ext4_ext_map_blocks_enter: OK
[  144.746259] Testing event ext4_ext_convert_to_initialized_fastpath: OK
[  144.855653] Testing event ext4_ext_convert_to_initialized_enter: OK
[  144.956267] Testing event ext4_truncate_exit: OK
[  145.036438] Testing event ext4_truncate_enter: OK
[  145.116332] Testing event ext4_unlink_exit: OK
[  145.196529] Testing event ext4_unlink_enter: OK
[  145.276488] Testing event ext4_fallocate_exit: OK
[  145.356634] Testing event ext4_zero_range: OK
[  145.436342] Testing event ext4_punch_hole: OK
[  145.516678] Testing event ext4_fallocate_enter: OK
[  145.599926] Testing event ext4_direct_IO_exit: OK
[  145.686098] Testing event ext4_direct_IO_enter: OK
[  145.766175] Testing event ext4_load_inode_bitmap: OK
[  145.855083] Testing event ext4_read_block_bitmap_load: OK
[  145.946108] Testing event ext4_mb_buddy_bitmap_load: OK
[  146.036090] Testing event ext4_mb_bitmap_load: OK
[  146.116248] Testing event ext4_da_release_space: OK
[  146.205319] Testing event ext4_da_reserve_space: OK
[  146.294794] Testing event ext4_da_update_reserve_space: OK
[  146.386240] Testing event ext4_forget: OK
[  146.456284] Testing event ext4_mballoc_free: OK
[  146.536168] Testing event ext4_mballoc_discard: OK
[  146.615834] Testing event ext4_mballoc_prealloc: OK
[  146.704080] Testing event ext4_mballoc_alloc: OK
[  146.785895] Testing event ext4_alloc_da_blocks: OK
[  146.866080] Testing event ext4_sync_fs: OK
[  146.944928] Testing event ext4_sync_file_exit: OK
[  147.025624] Testing event ext4_sync_file_enter: OK
[  147.105226] Testing event ext4_free_blocks: OK
[  147.185545] Testing event ext4_allocate_blocks: OK
[  147.265716] Testing event ext4_request_blocks: OK
[  147.345749] Testing event ext4_mb_discard_preallocations: OK
[  147.435495] Testing event ext4_discard_preallocations: OK
[  147.525520] Testing event ext4_mb_release_group_pa: OK
[  147.615654] Testing event ext4_mb_release_inode_pa: OK
[  147.706323] Testing event ext4_mb_new_group_pa: OK
[  147.785788] Testing event ext4_mb_new_inode_pa: OK
[  147.865607] Testing event ext4_discard_blocks: OK
[  147.944961] Testing event ext4_journalled_invalidatepage: OK
[  148.035299] Testing event ext4_invalidatepage: OK
[  148.115809] Testing event ext4_releasepage: OK
[  148.195614] Testing event ext4_readpage: OK
[  148.274439] Testing event ext4_writepage: OK
[  148.354655] Testing event ext4_writepages_result: OK
[  148.434190] Testing event ext4_da_write_pages_extent: OK
[  148.525730] Testing event ext4_da_write_pages: OK
[  148.605497] Testing event ext4_writepages: OK
[  148.685362] Testing event ext4_da_write_end: OK
[  148.766722] Testing event ext4_journalled_write_end: OK
[  148.855455] Testing event ext4_write_end: OK
[  148.935855] Testing event ext4_da_write_begin: OK
[  149.015614] Testing event ext4_write_begin: OK
[  149.095606] Testing event ext4_begin_ordered_truncate: OK
[  149.185554] Testing event ext4_mark_inode_dirty: OK
[  149.274804] Testing event ext4_drop_inode: OK
[  149.355938] Testing event ext4_evict_inode: OK
[  149.435409] Testing event ext4_allocate_inode: OK
[  149.515346] Testing event ext4_request_inode: OK
[  149.599222] Testing event ext4_free_inode: OK
[  149.675493] Testing event ext4_other_inode_update_time: OK
[  149.765103] Testing event jbd2_lock_buffer_stall: OK
[  149.855854] Testing event jbd2_write_superblock: OK
[  149.944460] Testing event jbd2_update_log_tail: OK
[  150.025737] Testing event jbd2_checkpoint_stats: OK
[  150.114674] Testing event jbd2_run_stats: OK
[  150.195685] Testing event jbd2_handle_stats: OK
[  150.275772] Testing event jbd2_handle_extend: OK
[  150.355356] Testing event jbd2_handle_start: OK
[  150.435287] Testing event jbd2_submit_inode_data: OK
[  150.524286] Testing event jbd2_end_commit: OK
[  150.605937] Testing event jbd2_drop_transaction: OK
[  150.694585] Testing event jbd2_commit_logging: OK
[  150.775232] Testing event jbd2_commit_flushing: OK
[  150.864521] Testing event jbd2_commit_locking: OK
[  150.945167] Testing event jbd2_start_commit: OK
[  151.025446] Testing event jbd2_checkpoint: OK
[  151.105210] Testing event block_rq_remap: OK
[  151.184879] Testing event block_bio_remap: OK
[  151.264779] Testing event block_split: OK
[  151.334594] Testing event block_unplug: OK
[  151.403726] Testing event block_plug: OK
[  151.474759] Testing event block_sleeprq: OK
[  151.553604] Testing event block_getrq: OK
[  151.634074] Testing event block_bio_queue: OK
[  151.714712] Testing event block_bio_frontmerge: OK
[  151.794188] Testing event block_bio_backmerge: OK
[  151.874273] Testing event block_bio_complete: OK
[  151.954099] Testing event block_bio_bounce: OK
[  152.034627] Testing event block_rq_issue: OK
[  152.114410] Testing event block_rq_insert: OK
[  152.193956] Testing event block_rq_complete: OK
[  152.274324] Testing event block_rq_requeue: OK
[  152.353532] Testing event block_rq_abort: OK
[  152.433203] Testing event block_dirty_buffer: OK
[  152.514195] Testing event block_touch_buffer: OK
[  152.593882] Testing event urandom_read: OK
[  152.663770] Testing event random_read: OK
[  152.733791] Testing event extract_entropy_user: OK
[  152.813844] Testing event extract_entropy: OK
[  152.893925] Testing event get_random_bytes_arch: OK
[  152.973977] Testing event get_random_bytes: OK
[  153.054498] Testing event xfer_secondary_pool: OK
[  153.134342] Testing event add_disk_randomness: OK
[  153.214084] Testing event add_input_randomness: OK
[  153.294111] Testing event debit_entropy: OK
[  153.373298] Testing event push_to_pool: OK
[  153.444574] Testing event credit_entropy_bits: OK
[  153.524415] Testing event mix_pool_bytes_nolock: OK
[  153.608547] Testing event mix_pool_bytes: OK
[  153.684589] Testing event add_device_randomness: OK
[  153.773941] Testing event scsi_eh_wakeup: OK
[  153.854699] Testing event scsi_dispatch_cmd_timeout: OK
[  153.944653] Testing event scsi_dispatch_cmd_done: OK
[  154.032981] Testing event scsi_dispatch_cmd_error: OK
[  154.123896] Testing event scsi_dispatch_cmd_start: OK
[  154.213354] Testing event ata_eh_link_autopsy_qc: OK
[  154.295058] Testing event ata_eh_link_autopsy: OK
[  154.374805] Testing event ata_qc_complete_done: OK
[  154.454597] Testing event ata_qc_complete_failed: OK
[  154.544619] Testing event ata_qc_complete_internal: OK
[  154.634505] Testing event ata_qc_issue: OK
[  154.714087] Testing event udp_fail_queue_rcv_skb: OK
[  154.803529] Testing event sock_exceed_buf_limit: OK
[  154.884808] Testing event sock_rcvqueue_full: OK
[  154.964587] Testing event napi_poll: OK
[  155.034713] Testing event netif_rx_ni_entry: OK
[  155.114668] Testing event netif_rx_entry: OK
[  155.194728] Testing event netif_receive_skb_entry: OK
[  155.284042] Testing event napi_gro_receive_entry: OK
[  155.373922] Testing event napi_gro_frags_entry: OK
[  155.454457] Testing event netif_rx: OK
[  155.524125] Testing event netif_receive_skb: OK
[  155.606493] Testing event net_dev_queue: OK
[  155.684698] Testing event net_dev_xmit: OK
[  155.763734] Testing event net_dev_start_xmit: OK
[  155.844750] Testing event skb_copy_datagram_iovec: OK
[  155.934485] Testing event consume_skb: OK
[  156.013844] Testing event kfree_skb: OK
[  156.084579] Running tests on trace event systems:
[  156.139690] Testing event system skb: OK
[  156.216431] Testing event system net: OK
[  156.298893] Testing event system napi: OK
[  156.375717] Testing event system sock: OK
[  156.455228] Testing event system udp: OK
[  156.524907] Testing event system libata: OK
[  156.606803] Testing event system scsi: OK
[  156.686414] Testing event system random: OK
[  156.770887] Testing event system block: OK
[  156.861683] Testing event system jbd2: OK
[  156.949521] Testing event system ext4: OK
[  157.081485] Testing event system filelock: OK
[  157.176198] Testing event system writeback: OK
[  157.274016] Testing event system migrate: OK
[  157.354007] Testing event system compaction: OK
[  157.438432] Testing event system kmem: OK
[  157.518831] Testing event system vmscan: OK
[  157.614440] Testing event system pagemap: OK
[  157.694126] Testing event system oom: OK
[  157.763815] Testing event system filemap: OK
[  157.844298] Testing event system rpm: OK
[  157.914642] Testing event system power: OK
[  158.002522] Testing event system test: OK
[  158.083674] Testing event system module: OK
[  158.164917] Testing event system timer: OK
[  158.248528] Testing event system rcu: OK
[  158.322896] Testing event system printk: OK
[  158.402866] Testing event system lock: OK
[  158.484389] Testing event system sched: OK
[  158.570692] Testing event system workqueue: OK
[  158.654721] Testing event system signal: OK
[  158.733597] Testing event system irq: OK
[  158.805118] Testing event system task: OK
[  158.883843] Testing event system raw_syscalls: OK
[  158.963830] Running tests on all trace events:
[  159.014810] Testing all events: OK
[  159.356882] Testing ftrace filter: OK
[  159.446319] Key type encrypted registered
[  159.494289] rtc-m48t59 rtc-m48t59.0: setting system clock to 2015-07-17 18:40:01 UTC (1437158401)
[  159.619842] EXT4-fs (sda1): couldn't mount as ext3 due to feature incompatibilities
[  159.704031] EXT4-fs (sda1): couldn't mount as ext2 due to feature incompatibilities
[  159.924771] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
[  160.002483] VFS: Mounted root (ext4 filesystem) readonly on device 8:1.
[  160.114485] devtmpfs: mounted
[  162.079289] systemd[1]: Inserted module 'autofs4'
[  162.369280] random: systemd urandom read with 13 bits of entropy available
[  162.587983] systemd[1]: systemd 218 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN)
[  162.799271] systemd[1]: Detected architecture 'sparc64'.
[  163.028157] systemd[1]: Set hostname to <u5>.
[  163.464486] systemd-system- (1149) used greatest stack depth: 6520 bytes left
[  164.148802] systemd-fstab-g (1152) used greatest stack depth: 4744 bytes left
[  166.075224] systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory.
[  166.255871] systemd[1]: Starting Arbitrary Executable File Formats File System Automount Point.
[  166.469391] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[  166.574904] systemd[1]: Expecting device dev-sda2.device...
[  166.699785] systemd[1]: Starting Dispatch Password Requests to Console Directory Watch.
[  166.788226] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[  166.871940] systemd[1]: Starting Root Slice.
[  166.969284] systemd[1]: Created slice Root Slice.
[  167.013738] systemd[1]: Starting Syslog Socket.
[  167.129256] systemd[1]: Listening on Syslog Socket.
[  167.175766] systemd[1]: Starting Journal Socket (/dev/log).
[  167.309237] systemd[1]: Listening on Journal Socket (/dev/log).
[  167.368789] systemd[1]: Starting Journal Audit Socket.
[  167.479183] systemd[1]: Listening on Journal Audit Socket.
[  167.532758] systemd[1]: Starting User and Session Slice.
[  167.669415] systemd[1]: Created slice User and Session Slice.
[  167.725972] systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
[  167.889152] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[  167.960300] systemd[1]: Starting Journal Socket.
[  168.059108] systemd[1]: Listening on Journal Socket.
[  168.106714] systemd[1]: Starting udev Kernel Socket.
[  168.229087] systemd[1]: Listening on udev Kernel Socket.
[  168.280535] systemd[1]: Starting udev Control Socket.
[  168.399065] systemd[1]: Listening on udev Control Socket.
[  168.450918] systemd[1]: Starting Remote File Systems (Pre).
[  168.579025] systemd[1]: Reached target Remote File Systems (Pre).
[  168.638962] systemd[1]: Starting Encrypted Volumes.
[  168.758999] systemd[1]: Reached target Encrypted Volumes.
[  168.810432] systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
[  168.891248] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  168.969127] systemd[1]: Starting Paths.
[  169.058953] systemd[1]: Reached target Paths.
[  169.097907] systemd[1]: Starting Delayed Shutdown Socket.
[  169.228944] systemd[1]: Listening on Delayed Shutdown Socket.
[  169.284561] systemd[1]: Starting System Slice.
[  169.378892] systemd[1]: Created slice System Slice.
[  169.427100] systemd[1]: Mounting Debug File System...
[  169.534489] systemd[1]: Starting system-serial\x2dgetty.slice.
[  169.689757] systemd[1]: Created slice system-serial\x2dgetty.slice.
[  169.761820] systemd[1]: Mounting Huge Pages File System...
[  169.913105] systemd[1]: Starting system-getty.slice.
[  170.139460] systemd[1]: Created slice system-getty.slice.
[  170.218850] systemd[1]: Starting Journal Service...
[  170.370607] systemd[1]: Starting udev Coldplug all Devices...
[  170.547303] systemd[1]: Starting Slices.
[  170.659677] systemd[1]: Reached target Slices.
[  170.953945] systemd[1]: Starting Load Kernel Modules...
[  171.286810] systemd[1]: Mounting POSIX Message Queue File System...
[  171.665586] systemd[1]: Starting File System Check on Root Device...
[  172.010035] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[  172.710452] systemd[1]: Started Set Up Additional Binary Formats.
[  172.847687] systemd[1]: Expecting device dev-ttyS0.device...
[  173.016392] systemd[1]: Mounted Debug File System.
[  173.159896] systemd[1]: Mounted Huge Pages File System.
[  173.331049] systemd[1]: Mounted POSIX Message Queue File System.
[  173.768130] systemd[1]: Started Load Kernel Modules.
[  174.108139] systemd[1]: Started File System Check on Root Device.
[  174.471637] systemd[1]: Started Create list of required static device nodes for the current kernel.
[  174.918039] systemd[1]: Started Journal Service.
[  178.947464] blk_update_request: I/O error, dev fd0, sector 0
[  179.001972] floppy: error -5 while reading block 0
[  180.737967] PCI: Enabling device: (0000:01:01.1), cmd 2
[  180.738173] sunhme.c:v3.10 August 26, 2008 David S. Miller (davem@...emloft.net)
[  180.831211] eth0: HAPPY MEAL (PCI/CheerIO) 10/100BaseT Ethernet 08:00:20:f8:c7:72
[  181.440052] sd 0:0:0:0: Attached scsi generic sg0 type 0
[  181.876958] sr 1:0:0:0: Attached scsi generic sg1 type 5
[  182.347615] cdrom_id (1485) used greatest stack depth: 4552 bytes left
[  182.541054] ls (1487) used greatest stack depth: 4216 bytes left
[  188.237648] Adding 1984016k swap on /dev/sda2.  Priority:-1 extents:1 across:1984016k 
[  189.973232] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro
[  191.015816] systemd-udevd (1449) used greatest stack depth: 3624 bytes left
[  191.467447] systemd-journald[1165]: Received request to flush runtime journal from PID 1
[  200.653020] eth0: Link is up using internal transceiver at 100Mb/s, Full Duplex.
[  291.022113] random: nonblocking pool is initialized

-- 
Meelis Roos (mroos@...ux.ee)
--
To unsubscribe from this list: send the line "unsubscribe netdev" in
the body of a message to majordomo@...r.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ