Re: [block] 47cdee29ef: BUG:kernel_NULL_pointer_dereference,address

From: Rong Chen
Date: Tue Jun 04 2019 - 05:10:35 EST

Next message: Yu-Hsuan Hsu: "[PATCH] ASoC: max98090: remove 24-bit format support if RJ is 0"
Previous message: Steven Rostedt: "Re: [PATCH] kprobes: Fix to init kprobes in subsys_initcall"
In reply to: Ming Lei: "Re: [block] 47cdee29ef: BUG:kernel_NULL_pointer_dereference,address"
Next in thread: Ming Lei: "Re: [block] 47cdee29ef: BUG:kernel_NULL_pointer_dereference,address"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

Hi,

On 6/4/19 12:03 PM, Ming Lei wrote:

Hi Rong Chen,

Thanks for your test & report!

On Tue, Jun 04, 2019 at 10:09:56AM +0800, kernel test robot wrote:

FYI, we noticed the following commit (built with gcc-7):

commit: 47cdee29ef9d94e485eb08f962c74943023a5271 ("block: move blk_exit_queue into __blk_release_queue")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master

in testcase: trinity
with following parameters:

runtime: 300s

test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/

on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 2G

caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):

+-------------------------------------------------+------------+------------+
| | 31cb1d64da | 47cdee29ef |
+-------------------------------------------------+------------+------------+
| boot_successes | 3 | 0 |
| boot_failures | 13 | 8 |
| BUG:kernel_reboot-without-warning_in_test_stage | 13 | |
| BUG:kernel_NULL_pointer_dereference,address | 0 | 8 |
| Oops:#[##] | 0 | 8 |
| RIP:blk_mq_free_rqs | 0 | 8 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 8 |
+-------------------------------------------------+------------+------------+

If you fix the issue, kindly add following tag
Reported-by: kernel test robot <rong.a.chen@xxxxxxxxx>

[ 6.560544] BUG: kernel NULL pointer dereference, address: 0000000000000020
[ 6.561658] #PF: supervisor read access in kernel mode
[ 6.562495] #PF: error_code(0x0000) - not-present page
[ 6.563277] PGD 0 P4D 0
[ 6.563277] Oops: 0000 [#1] PTI
[ 6.563277] CPU: 0 PID: 147 Comm: kworker/0:2 Tainted: G T 5.2.0-rc1-00387-g47cdee29 #1
[ 6.563277] Workqueue: events __blk_release_queue
[ 6.563277] RIP: 0010:blk_mq_free_rqs+0x2c/0xaf

Looks there is race between removing queue and switching elevator, and
which should be done by Trinity.

I guess that commit 47cdee29ef9d94e485eb08f962c74943023a5271 just
changes the timing and makes it easy to trigger.

Please test the following patch and see if difference can be made.
If the patch can't fix the issue, please enable KASAN and reproduce,
then more useful log may be got.

The patch doesn't work, Attached please find the dmesg file with KASAN enabled.

Best Regards,
Rong Chen

diff --git a/block/blk-sysfs.c b/block/blk-sysfs.c
index 75b5281cc577..400a2102a4e4 100644
--- a/block/blk-sysfs.c
+++ b/block/blk-sysfs.c
@@ -848,11 +848,13 @@ static void blk_exit_queue(struct request_queue *q)
* perform I/O scheduler exit before disassociating from the block
* cgroup controller.
*/
+ mutex_lock(&q->sysfs_lock);
if (q->elevator) {
ioc_clear_queue(q);
elevator_exit(q, q->elevator);
q->elevator = NULL;
}
+ mutex_unlock(&q->sysfs_lock);
/*
* Remove all references to @q from the block cgroup controller before

Thanks,
Ming

early console in setup code
early console in extract_kernel
input_data: 0x00000000062252c8
input_len: 0x00000000015ef68d
output: 0x0000000001000000
output_len: 0x00000000059cf9a8
kernel_total_size: 0x0000000006c26000
trampoline_32bit: 0x000000000009d000

Decompressing Linux... Parsing ELF... done.
Booting the kernel.
[ 0.000000] Linux version 5.2.0-rc1-00387-g47cdee29 (kbuild@lkp-kbuild06) (gcc version 6.4.0 20171026 (Debian 6.4.0-9)) #1 SMP PREEMPT Tue Jun 4 16:11:00 CST 2019
[ 0.000000] Command line: root=/dev/ram0 hung_task_panic=1 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw link=/cephfs/kbuild/run-queue/yocto-vm-yocto/x86_64-randconfig-ne0-06030921+CONFIG_KASAN/linux-devel:fixup-47cdee29ef9d94e485eb08f962c74943023a5271:47cdee29ef9d94e485eb08f962c74943023a5271:bisect-linux-46/.vmlinuz-47cdee29ef9d94e485eb08f962c74943023a5271-20190604162844-6:yocto-vm-yocto-202 branch=linux-devel/fixup-47cdee29ef9d94e485eb08f962c74943023a5271 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-ne0-06030921+CONFIG_KASAN/gcc-6/47cdee29ef9d94e485eb08f962c74943023a5271/vmlinuz-5.2.0-rc1-00387-g47cdee29 drbd.minor_count=8 rcuperf.shutdown=0
[ 0.000000] x86/fpu: x87 FPU will use FXSAVE
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001ffdffff] usable
[ 0.000000] BIOS-e820: [mem 0x000000001ffe0000-0x000000001fffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[ 0.000000] printk: debug: ignoring loglevel setting.
[ 0.000000] printk: bootconsole [earlyser0] enabled
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] SMBIOS 2.8 present.
[ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 0.000000] Hypervisor detected: KVM
[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[ 0.000031] kvm-clock: cpu 0, msr 7a01001, primary cpu clock
[ 0.000032] kvm-clock: using sched offset of 33565616772 cycles
[ 0.009917] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[ 0.038400] tsc: Detected 2393.998 MHz processor
[ 0.070066] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[ 0.082214] e820: remove [mem 0x000a0000-0x000fffff] usable
[ 0.095370] last_pfn = 0x1ffe0 max_arch_pfn = 0x400000000
[ 0.104737] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC
[ 0.114909] Scan for SMP in [mem 0x00000000-0x000003ff]
[ 0.122396] Scan for SMP in [mem 0x0009fc00-0x0009ffff]
[ 0.129859] Scan for SMP in [mem 0x000f0000-0x000fffff]
[ 0.211421] found SMP MP-table at [mem 0x000f6a80-0x000f6a8f]
[ 0.222374] mpc: f6a90-f6b74
[ 0.228885] check: Scanning 1 areas for low memory corruption
[ 0.240068] BRK [0x07c01000, 0x07c01fff] PGTABLE
[ 0.249231] BRK [0x07c02000, 0x07c02fff] PGTABLE
[ 0.257665] BRK [0x07c03000, 0x07c03fff] PGTABLE
[ 0.268679] BRK [0x07c04000, 0x07c04fff] PGTABLE
[ 0.320493] BRK [0x07c05000, 0x07c05fff] PGTABLE
[ 0.328925] BRK [0x07c06000, 0x07c06fff] PGTABLE
[ 0.338642] RAMDISK: [mem 0x1fa0e000-0x1ffdffff]
[ 0.347099] ACPI: Early table checksum verification disabled
[ 0.358159] ACPI: RSDP 0x00000000000F6870 000014 (v00 BOCHS )
[ 0.368748] ACPI: RSDT 0x000000001FFE15C9 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
[ 0.384407] ACPI: FACP 0x000000001FFE149D 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001)
[ 0.399468] ACPI: DSDT 0x000000001FFE0040 00145D (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001)
[ 0.414865] ACPI: FACS 0x000000001FFE0000 000040
[ 0.422999] ACPI: APIC 0x000000001FFE1511 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001)
[ 0.438553] ACPI: HPET 0x000000001FFE1591 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001)
[ 0.454048] ACPI: Local APIC address 0xfee00000
[ 0.462045] mapped APIC to ffffffffff5fd000 ( fee00000)
[ 0.477308] No NUMA configuration found
[ 0.484347] Faking a node at [mem 0x0000000000000000-0x000000001ffdffff]
[ 0.496365] NODE_DATA(0) allocated [mem 0x1fa0a000-0x1fa0dfff]
[ 0.506671] cma: dma_contiguous_reserve(limit 1ffe0000)
[ 0.518754] Zone ranges:
[ 0.523326] DMA32 [mem 0x0000000000001000-0x000000001ffdffff]
[ 0.534248] Normal empty
[ 0.539499] Movable zone start for each node
[ 0.547183] Early memory node ranges
[ 0.553109] node 0: [mem 0x0000000000001000-0x000000000009efff]
[ 0.563669] node 0: [mem 0x0000000000100000-0x000000001ffdffff]
[ 0.576249] Zeroed struct page in unavailable ranges: 98 pages
[ 0.576306] Initmem setup node 0 [mem 0x0000000000001000-0x000000001ffdffff]
[ 0.597572] On node 0 totalpages: 130942
[ 0.604183] DMA32 zone: 1792 pages used for memmap
[ 0.612346] DMA32 zone: 21 pages reserved
[ 0.619376] DMA32 zone: 130942 pages, LIFO batch:31
[ 0.819125] kasan: KernelAddressSanitizer initialized
[ 0.831968] ACPI: PM-Timer IO Port: 0x608
[ 0.839190] ACPI: Local APIC address 0xfee00000
[ 0.846445] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[ 0.854746] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[ 0.903940] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.919331] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[ 0.932352] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[ 0.944052] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[ 0.956138] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.967678] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[ 0.980414] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[ 0.992337] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[ 1.004659] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[ 1.016843] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[ 1.029736] ACPI: IRQ0 used by override.
[ 1.036868] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[ 1.048433] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[ 1.058477] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[ 1.070598] ACPI: IRQ5 used by override.
[ 1.075875] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[ 1.079126] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[ 1.081913] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[ 1.090164] ACPI: IRQ9 used by override.
[ 1.096932] ACPI: IRQ10 used by override.
[ 1.104002] ACPI: IRQ11 used by override.
[ 1.110971] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[ 1.121244] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[ 1.131738] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[ 1.143862] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[ 1.157081] Using ACPI (MADT) for SMP configuration information
[ 1.168233] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[ 1.178085] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[ 1.187284] mapped IOAPIC to ffffffffff5fc000 (fec00000)
[ 1.197206] [mem 0x20000000-0xfeffbfff] available for PCI devices
[ 1.208278] Booting paravirtualized kernel on KVM
[ 1.216841] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[ 9.077066] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:2 nr_node_ids:1
[ 9.121691] percpu: Embedded 514 pages/cpu s2076672 r0 d28672 u4194304
[ 9.133915] pcpu-alloc: s2076672 r0 d28672 u4194304 alloc=2*2097152
[ 9.145510] pcpu-alloc: [0] 0 [0] 1
[ 9.152417] KVM setup async PF for cpu 0
[ 9.159868] kvm-stealtime: cpu 0, msr 199fa040
[ 9.172759] Built 1 zonelists, mobility grouping on. Total pages: 129129
[ 9.185009] Policy zone: DMA32
[ 9.190745] Kernel command line: root=/dev/ram0 hung_task_panic=1 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw link=/cephfs/kbuild/run-queue/yocto-vm-yocto/x86_64-randconfig-ne0-06030921+CONFIG_KASAN/linux-devel:fixup-47cdee29ef9d94e485eb08f962c74943023a5271:47cdee29ef9d94e485eb08f962c74943023a5271:bisect-linux-46/.vmlinuz-47cdee29ef9d94e485eb08f962c74943023a5271-20190604162844-6:yocto-vm-yocto-202 branch=linux-devel/fixup-47cdee29ef9d94e485eb08f962c74943023a5271 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-ne0-06030921+CONFIG_KASAN/gcc-6/47cdee29ef9d94e485eb08f962c74943023a5271/vmlinuz-5.2.0-rc1-00387-g47cdee29 drbd.minor_count=8 rcuperf.shutdown=0
[ 9.353912] sysrq: sysrq always enabled.
[ 10.285687] Memory: 151048K/523768K available (47108K kernel code, 12195K rwdata, 14284K rodata, 4620K init, 22724K bss, 212292K reserved, 0K cma-reserved)
[ 10.312684] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 10.389617] ODEBUG: selftest passed
[ 10.396840] ftrace: allocating 67060 entries in 262 pages
[ 10.766574]
[ 10.769656] **********************************************************
[ 10.781932] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE **
[ 10.793667] ** **
[ 10.805242] ** trace_printk() being used. Allocating extra memory. **
[ 10.817095] ** **
[ 10.828665] ** This means that this is a DEBUG kernel and it is **
[ 10.840144] ** unsafe for production use. **
[ 10.851897] ** **
[ 10.864246] ** If you see this message and you are not debugging **
[ 10.876882] ** the kernel, report this immediately to your vendor! **
[ 10.889233] ** **
[ 10.901387] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE **
[ 10.913769] **********************************************************
[ 11.005666] rcu: Preemptible hierarchical RCU implementation.
[ 11.015944] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=2.
[ 11.027540] rcu: RCU callback double-/use-after-free debug enabled.
[ 11.038493] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[ 11.051431] Tasks RCU enabled.
[ 11.057157] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[ 11.071000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[ 11.260087] NR_IRQS: 4352, nr_irqs: 56, preallocated irqs: 16
[ 11.290065] random: get_random_bytes called from start_kernel+0x412/0x79a with crng_init=0
[ 11.308370] random: get_random_u64 called from cache_random_seq_create+0x54/0xe9 with crng_init=0
[ 11.336308] printk: console [ttyS0] enabled
[ 11.352088] printk: bootconsole [earlyser0] disabled
[ 11.370499] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 11.385377] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 11.393569] ... MAX_LOCK_DEPTH: 48
[ 11.401441] ... MAX_LOCKDEP_KEYS: 8191
[ 11.409613] ... CLASSHASH_SIZE: 4096
[ 11.418101] ... MAX_LOCKDEP_ENTRIES: 32768
[ 11.426328] ... MAX_LOCKDEP_CHAINS: 65536
[ 11.434310] ... CHAINHASH_SIZE: 32768
[ 11.442338] memory used by lock dependency info: 4019 kB
[ 11.452612] per task-struct memory footprint: 2688 bytes
[ 11.457520] ------------------------
[ 11.459099] | Locking API testsuite:
[ 11.460451] ----------------------------------------------------------------------------
[ 11.463153] | spin |wlock |rlock |mutex | wsem | rsem |
[ 11.468101] --------------------------------------------------------------------------
[ 11.483136] A-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 11.559388] A-B-B-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 11.639894] A-B-B-C-C-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 11.723237] A-B-C-A-B-C deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 11.806097] A-B-B-C-C-D-D-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 11.898232] A-B-C-D-B-D-D-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 11.955495] A-B-C-D-B-C-D-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 12.015365] double unlock: ok | ok | ok | ok | ok | ok | ok |
[ 12.087349] initialize held: ok | ok | ok | ok | ok | ok | ok |
[ 12.141692] --------------------------------------------------------------------------
[ 12.144468] recursive read-lock: | ok | |failed|
[ 12.169883] recursive read-lock #2: | ok | |failed|
[ 12.202256] mixed read-write-lock: |failed| |failed|
[ 12.232526] mixed write-read-lock: |failed| |failed|
[ 12.264955] mixed read-lock/lock-write ABBA: |failed| |failed|
[ 12.271888] mixed read-lock/lock-read ABBA: | ok | |failed|
[ 12.286033] mixed write-lock/lock-write ABBA: |failed| |failed|
[ 12.317301] --------------------------------------------------------------------------
[ 12.345753] hard-irqs-on + irq-safe-A/12:failed|failed| ok |
[ 12.380562] soft-irqs-on + irq-safe-A/12:failed|failed| ok |
[ 12.392818] hard-irqs-on + irq-safe-A/21:failed|failed| ok |
[ 12.417168] soft-irqs-on + irq-safe-A/21:failed|failed| ok |
[ 12.452266] sirq-safe-A => hirqs-on/12:failed|failed| ok |
[ 12.486944] sirq-safe-A => hirqs-on/21:failed|failed| ok |
[ 12.521903] hard-safe-A + irqs-on/12:failed|failed| ok |
[ 12.557558] soft-safe-A + irqs-on/12:failed|failed| ok |
[ 12.594979] hard-safe-A + irqs-on/21:failed|failed| ok |
[ 12.658010] soft-safe-A + irqs-on/21:failed|failed| ok |
[ 12.695569] hard-safe-A + unsafe-B #1/123:failed|failed| ok |
[ 12.734100] soft-safe-A + unsafe-B #1/123:failed|failed| ok |
[ 12.772053] hard-safe-A + unsafe-B #1/132:failed|failed| ok |
[ 12.810383] soft-safe-A + unsafe-B #1/132:failed|failed| ok |
[ 12.847656] hard-safe-A + unsafe-B #1/213:failed|failed| ok |
[ 12.885243] soft-safe-A + unsafe-B #1/213:failed|failed| ok |
[ 12.923064] hard-safe-A + unsafe-B #1/231:failed|failed| ok |
[ 12.960490] soft-safe-A + unsafe-B #1/231:failed|failed| ok |
[ 12.997922] hard-safe-A + unsafe-B #1/312:failed|failed| ok |
[ 13.035060] soft-safe-A + unsafe-B #1/312:failed|failed| ok |
[ 13.054751] hard-safe-A + unsafe-B #1/321:failed|failed| ok |
[ 13.073285] soft-safe-A + unsafe-B #1/321:failed|failed| ok |
[ 13.105593] hard-safe-A + unsafe-B #2/123:failed|failed| ok |
[ 13.116023] soft-safe-A + unsafe-B #2/123:failed|failed| ok |
[ 13.152351] hard-safe-A + unsafe-B #2/132:failed|failed| ok |
[ 13.189002] soft-safe-A + unsafe-B #2/132:failed|failed| ok |
[ 13.225592] hard-safe-A + unsafe-B #2/213:failed|failed| ok |
[ 13.258007] soft-safe-A + unsafe-B #2/213:failed|failed| ok |
[ 13.266488] hard-safe-A + unsafe-B #2/231:failed|failed| ok |
[ 13.303818] soft-safe-A + unsafe-B #2/231:failed|failed| ok |
[ 13.341466] hard-safe-A + unsafe-B #2/312:failed|failed| ok |
[ 13.379100] soft-safe-A + unsafe-B #2/312:failed|failed| ok |
[ 13.415710] hard-safe-A + unsafe-B #2/321:failed|failed| ok |
[ 13.429135] soft-safe-A + unsafe-B #2/321:failed|failed| ok |
[ 13.457356] hard-irq lock-inversion/123:failed|failed| ok |
[ 13.494010] soft-irq lock-inversion/123:failed|failed| ok |
[ 13.530400] hard-irq lock-inversion/132:failed|failed| ok |
[ 13.567636] soft-irq lock-inversion/132:failed|failed| ok |
[ 13.604710] hard-irq lock-inversion/213:failed|failed| ok |
[ 13.639522] soft-irq lock-inversion/213:failed|failed| ok |
[ 13.647563] hard-irq lock-inversion/231:failed|failed| ok |
[ 13.680924] soft-irq lock-inversion/231:failed|failed| ok |
[ 13.692172] hard-irq lock-inversion/312:failed|failed| ok |
[ 13.725020] soft-irq lock-inversion/312:failed|failed| ok |
[ 13.751060] hard-irq lock-inversion/321:failed|failed| ok |
[ 13.762868] soft-irq lock-inversion/321:failed|failed| ok |
[ 13.801004] hard-irq read-recursion/123: ok |
[ 13.819028] soft-irq read-recursion/123: ok |
[ 13.835662] hard-irq read-recursion/132: ok |
[ 13.840019] soft-irq read-recursion/132: ok |
[ 13.843340] hard-irq read-recursion/213: ok |
[ 13.849994] soft-irq read-recursion/213: ok |
[ 13.868171] hard-irq read-recursion/231: ok |
[ 13.882597] soft-irq read-recursion/231: ok |
[ 13.887015] hard-irq read-recursion/312: ok |
[ 13.890398] soft-irq read-recursion/312: ok |
[ 13.904846] hard-irq read-recursion/321: ok |
[ 13.923093] soft-irq read-recursion/321: ok |
[ 13.929945] --------------------------------------------------------------------------
[ 13.932841] | Wound/wait tests |
[ 13.933933] ---------------------
[ 13.934948] ww api failures: ok | ok | ok |
[ 13.968389] ww contexts mixing:failed| ok |
[ 13.994640] finishing ww context: ok | ok | ok | ok |
[ 14.014060] locking mismatches: ok | ok | ok |
[ 14.045460] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok |
[ 14.142922] spinlock nest unlocked: ok |
[ 14.146503] -----------------------------------------------------
[ 14.148362] |block | try |context|
[ 14.152201] -----------------------------------------------------
[ 14.163443] context:failed| ok | ok |
[ 14.201332] try:failed| ok |failed|
[ 14.236886] block:failed| ok |failed|
[ 14.272496] spinlock:failed| ok |failed|
[ 14.310318] --------------------------------------------------------
[ 14.321494] 153 out of 262 testcases failed, as expected. |
[ 14.331381] ----------------------------------------------------
[ 14.352341] ACPI: Core revision 20190509
[ 14.370107] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[ 14.387545] hpet clockevent registered
[ 14.396323] APIC: Switch to symmetric I/O mode setup
[ 14.407021] enabled ExtINT on CPU#0
[ 14.439431] ENABLING IO-APIC IRQs
[ 14.447469] init IO_APIC IRQs
[ 14.456954] apic 0 pin 0 not connected
[ 14.482006] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:1)
[ 14.504825] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[ 14.520186] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:1)
[ 14.541411] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:1)
[ 14.544835] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:1)
[ 14.547561] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:1)
[ 14.553498] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:1)
[ 14.569151] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:1)
[ 14.584869] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:1)
[ 14.600915] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:1)
[ 14.617245] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:1)
[ 14.633251] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:1)
[ 14.649427] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:1)
[ 14.665304] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:1)
[ 14.681378] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:1)
[ 14.697161] apic 0 pin 16 not connected
[ 14.704679] apic 0 pin 17 not connected
[ 14.712458] apic 0 pin 18 not connected
[ 14.720065] apic 0 pin 19 not connected
[ 14.727714] apic 0 pin 20 not connected
[ 14.735107] apic 0 pin 21 not connected
[ 14.742719] apic 0 pin 22 not connected
[ 14.750436] apic 0 pin 23 not connected
[ 14.761387] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 14.773023] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22821188adc, max_idle_ns: 440795282349 ns
[ 14.801541] Calibrating delay loop (skipped) preset value.. 4787.99 BogoMIPS (lpj=9575992)
[ 14.805667] pid_max: default: 4096 minimum: 301
[ 14.822531] LSM: Security Framework initializing
[ 14.829988] Yama: becoming mindful.
[ 14.838378] LoadPin: ready to pin (currently not enforcing)
[ 14.847712] Smack: Initializing.
[ 14.849578] Smack: IPv6 port labeling enabled.
[ 14.859933] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[ 14.867994] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[ 14.875601] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[ 14.878182] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[ 14.916420] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 14.917600] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 14.921659] Spectre V2 : Spectre mitigation: kernel not compiled with retpoline; no mitigation available!
[ 14.921670] Speculative Store Bypass: Vulnerable
[ 14.933702] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
[ 14.943993] debug: unmapping init [mem 0xffffffff865c2000-0xffffffff865cefff]
[ 14.976675] TSC deadline timer enabled
[ 14.977471] smpboot: CPU0: Intel Core Processor (Haswell) (family: 0x6, model: 0x3c, stepping: 0x4)
[ 15.002609] Performance Events: unsupported p6 CPU model 60 no PMU driver, software events only.
[ 15.013863] rcu: Hierarchical SRCU implementation.
[ 15.132426] NMI watchdog: Perf NMI watchdog permanently disabled
[ 15.149832] smp: Bringing up secondary CPUs ...
[ 15.297471] x86: Booting SMP configuration:
[ 15.301662] .... node #0, CPUs: #1
[ 3.457577] kvm-clock: cpu 1, msr 7a01041, secondary cpu clock
[ 3.457577] masked ExtINT on CPU#1
[ 15.342540] KVM setup async PF for cpu 1
[ 15.342540] kvm-stealtime: cpu 1, msr 19dfa040
[ 15.385757] smp: Brought up 1 node, 2 CPUs
[ 15.390089] smpboot: Max logical packages: 2
[ 15.393666] smpboot: Total of 2 processors activated (9575.99 BogoMIPS)
[ 15.417632] node 0 initialised, 40107 pages in 4ms
[ 15.425726] pgdatinit0 (19) used greatest stack depth: 30184 bytes left
[ 15.427780] devtmpfs: initialized
[ 15.443673] x86/mm: Memory block size: 128MB
[ 15.562281] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 15.565703] futex hash table entries: 16 (order: -1, 2048 bytes)
[ 15.570823] xor: measuring software checksum speed
[ 15.609630] prefetch64-sse: 1042.000 MB/sec
[ 15.653632] generic_sse: 839.000 MB/sec
[ 15.657662] xor: using function: prefetch64-sse (1042.000 MB/sec)
[ 15.661767] prandom: seed boundary self test passed
[ 15.682374] prandom: 100 self tests passed
[ 15.685663] pinctrl core: initialized pinctrl subsystem
[ 15.732720] PM: RTC time: 16:29:51, date: 2019-06-04
[ 15.748937] _warn_unseeded_randomness: 6 callbacks suppressed
[ 15.749471] random: get_random_u32 called from bucket_table_alloc+0x210/0x286 with crng_init=0
[ 15.754995] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 15.757834] random: get_random_u32 called from bucket_table_alloc+0x210/0x286 with crng_init=0
[ 15.767777] NET: Registered protocol family 16
[ 15.845726] cpuidle: using governor ladder
[ 15.852434] ACPI: bus type PCI registered
[ 15.853901] PCI: Using configuration type 1 for base access
[ 17.234272] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 17.454993] cryptd: max_cpu_qlen set to 1000
[ 17.738508] _warn_unseeded_randomness: 9 callbacks suppressed
[ 17.738508] random: get_random_u64 called from cache_random_seq_create+0x54/0xe9 with crng_init=0
[ 17.764774] random: get_random_u64 called from copy_process+0x5b5/0x4642 with crng_init=0
[ 17.788158] raid6: using algorithm sse2x4 gen() 0 MB/s
[ 17.789958] raid6: .... xor() 0 MB/s, rmw enabled
[ 17.794406] raid6: using ssse3x2 recovery algorithm
[ 17.798905] gpio-f7188x: Not a Fintek device at 0x0000002e
[ 17.801166] gpio-f7188x: Not a Fintek device at 0x0000004e
[ 17.815571] ACPI: Added _OSI(Module Device)
[ 17.817664] ACPI: Added _OSI(Processor Device)
[ 17.821639] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 17.825667] ACPI: Added _OSI(Processor Aggregator Device)
[ 17.829965] ACPI: Added _OSI(Linux-Dell-Video)
[ 17.833990] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[ 17.837953] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[ 18.338881] ACPI: 1 ACPI AML tables successfully acquired and loaded
[ 18.537222] ACPI: Interpreter enabled
[ 18.542827] ACPI: (supports S0 S5)
[ 18.550217] ACPI: Using IOAPIC for interrupt routing
[ 18.561388] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 18.628838] ACPI: Enabled 2 GPEs in block 00 to 0F
[ 20.180587] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 20.190315] acpi PNP0A03:00: _OSC: OS supports [Segments HPX-Type3]
[ 20.194907] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[ 20.199740] PCI host bridge to bus 0000:00
[ 20.209983] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 20.217824] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 20.229924] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[ 20.241917] pci_bus 0000:00: root bus resource [mem 0x20000000-0xfebfffff window]
[ 20.253832] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
[ 20.265858] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 20.274986] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[ 20.340547] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[ 20.412899] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[ 20.457792] pci 0000:00:01.1: reg 0x20: [io 0xc040-0xc04f]
[ 20.489926] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
[ 20.497517] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
[ 20.499897] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
[ 20.501522] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
[ 20.548257] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[ 20.559388] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
[ 20.561532] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
[ 20.610937] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[ 20.633864] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[ 20.673795] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[ 20.725770] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[ 20.803934] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[ 20.825691] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[ 20.849665] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f]
[ 20.912927] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[ 20.958375] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000
[ 20.969323] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f]
[ 21.201994] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[ 21.235967] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[ 21.253167] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[ 21.286104] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[ 21.298312] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[ 21.425849] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[ 21.427721] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[ 21.429695] pci 0000:00:02.0: vgaarb: bridge control possible
[ 21.433632] vgaarb: loaded
[ 21.459900] random: get_random_u64 called from cache_random_seq_create+0x54/0xe9 with crng_init=0
[ 21.476162] ACPI: bus type USB registered
[ 21.482314] usbcore: registered new interface driver usbfs
[ 21.487894] usbcore: registered new interface driver hub
[ 21.492457] usbcore: registered new device driver usb
[ 21.512419] media: Linux media interface: v0.10
[ 21.514151] videodev: Linux video capture interface: v2.00
[ 21.518973] pps_core: LinuxPPS API ver. 1 registered
[ 21.520396] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@xxxxxxxx>
[ 21.530705] random: get_random_u64 called from copy_process+0x5b5/0x4642 with crng_init=0
[ 21.569320] FPGA manager framework
[ 21.580095] Advanced Linux Sound Architecture Driver Initialized.
[ 21.584322] PCI: Using ACPI for IRQ routing
[ 21.585642] PCI: pci_cache_line_size set to 64 bytes
[ 21.591999] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[ 21.593958] e820: reserve RAM buffer [mem 0x1ffe0000-0x1fffffff]
[ 21.631260] random: get_random_u32 called from bucket_table_alloc+0x210/0x286 with crng_init=0
[ 21.636518] Bluetooth: Core ver 2.22
[ 21.639707] NET: Registered protocol family 31
[ 21.641724] Bluetooth: HCI device and connection manager initialized
[ 21.646025] Bluetooth: HCI socket layer initialized
[ 21.649824] Bluetooth: L2CAP socket layer initialized
[ 21.654507] Bluetooth: SCO socket layer initialized
[ 21.658407] NET: Registered protocol family 8
[ 21.661640] NET: Registered protocol family 20
[ 21.667669] NetLabel: Initializing
[ 21.669770] NetLabel: domain hash size = 128
[ 21.673636] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
[ 21.684096] NetLabel: unlabeled traffic allowed by default
[ 21.709471] clocksource: Switched to clocksource kvm-clock
[ 31.953035] _warn_unseeded_randomness: 1 callbacks suppressed
[ 31.953104] random: get_random_u64 called from cache_random_seq_create+0x54/0xe9 with crng_init=0
[ 31.997174] VFS: Disk quotas dquot_6.6.0
[ 32.007093] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 32.027636] FS-Cache: Loaded
[ 32.044612] pnp: PnP ACPI init
[ 32.065168] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[ 32.090732] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[ 32.114617] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[ 32.130945] pnp 00:03: [dma 2]
[ 32.142064] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[ 32.173466] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[ 32.202948] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[ 32.232829] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[ 32.338864] pnp: PnP ACPI: found 7 devices
[ 32.440150] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[ 32.460790] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
[ 32.472816] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
[ 32.484512] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[ 32.497343] pci_bus 0000:00: resource 7 [mem 0x20000000-0xfebfffff window]
[ 32.510441] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
[ 32.548131] NET: Registered protocol family 2
[ 32.558207] random: get_random_u32 called from neigh_hash_alloc+0x11a/0x15d with crng_init=0
[ 32.580492] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 32.628681] tcp_listen_portaddr_hash hash table entries: 256 (order: 2, 22528 bytes)
[ 32.646597] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[ 32.663164] TCP bind hash table entries: 4096 (order: 6, 327680 bytes)
[ 32.678781] TCP: Hash tables configured (established 4096 bind 4096)
[ 32.699519] UDP hash table entries: 256 (order: 3, 49152 bytes)
[ 32.712653] UDP-Lite hash table entries: 256 (order: 3, 49152 bytes)
[ 32.736721] NET: Registered protocol family 1
[ 32.767956] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[ 32.778453] pci 0000:00:00.0: quirk_passive_release+0x0/0xdb took 10408 usecs
[ 32.790808] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 32.801205] pci 0000:00:00.0: quirk_natoma+0x0/0x92 took 10121 usecs
[ 32.812707] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[ 32.823594] pci 0000:00:01.0: quirk_isa_dma_hangs+0x0/0x63 took 10578 usecs
[ 32.836565] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[ 32.851073] pci 0000:00:02.0: pci_fixup_video+0x0/0x263 took 14460 usecs
[ 32.863202] PCI: CLS 0 bytes, default 64
[ 32.883058] Unpacking initramfs...
[ 39.822707] debug: unmapping init [mem 0xffff88801fa0e000-0xffff88801ffdffff]
[ 39.877988] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22821188adc, max_idle_ns: 440795282349 ns
[ 39.943768] check: Scanning for low memory corruption every 60 seconds
[ 40.080176] PCLMULQDQ-NI instructions are not detected.
[ 40.109115] AVX or AES-NI instructions are not detected.
[ 40.120264] CPU feature 'AVX registers' is not supported.
[ 40.130633] CPU feature 'AVX registers' is not supported.
[ 40.141065] CPU feature 'AVX registers' is not supported.
[ 40.151205] CPU feature 'AVX registers' is not supported.
[ 40.160961] AVX2 or AES-NI instructions are not detected.
[ 40.170880] AVX2 instructions are not detected.
[ 40.183644] spin_lock-torture:--- Start of test [debug]: nwriters_stress=4 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0
[ 40.214590] spin_lock-torture: Creating torture_shuffle task
[ 40.229414] spin_lock-torture: torture_shuffle task started
[ 40.230697] spin_lock-torture: Creating torture_stutter task
[ 40.266671] spin_lock-torture: Creating lock_torture_writer task
[ 40.278563] spin_lock-torture: torture_stutter task started
[ 40.284769] spin_lock-torture: Creating lock_torture_writer task
[ 40.289968] spin_lock-torture: lock_torture_writer task started
[ 40.302844] spin_lock-torture: Creating lock_torture_writer task
[ 40.322933] spin_lock-torture: lock_torture_writer task started
[ 40.336550] spin_lock-torture: Creating lock_torture_writer task
[ 40.349832] spin_lock-torture: lock_torture_writer task started
[ 40.364177] spin_lock-torture: Creating lock_torture_stats task
[ 40.364323] spin_lock-torture: lock_torture_writer task started
[ 40.381808] spin_lock-torture: lock_torture_stats task started
[ 40.933910] _warn_unseeded_randomness: 10 callbacks suppressed
[ 40.934003] random: get_random_u32 called from get_random_order+0x9a/0x11e with crng_init=0
[ 44.980362] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 44.980436] random: get_random_u32 called from get_random_order+0x9a/0x11e with crng_init=0
[ 45.041161] random: get_random_u32 called from stress_one_work+0x71/0x122 with crng_init=0
[ 47.418658] torture_init_begin: Refusing rcu init: spin_lock running.
[ 47.438967] torture_init_begin: One torture test at a time!
[ 47.451638] torture_init_begin: Refusing rcu init: spin_lock running.
[ 47.463475] torture_init_begin: One torture test at a time!
[ 47.507031] _warn_unseeded_randomness: 6 callbacks suppressed
[ 47.507121] random: get_random_u64 called from cache_random_seq_create+0x54/0xe9 with crng_init=0
[ 47.667155] random: get_random_u64 called from copy_process+0x5b5/0x4642 with crng_init=0
[ 47.731952] workingset: timestamp_bits=56 max_order=17 bucket_order=0
[ 47.753342] random: get_random_u64 called from cache_random_seq_create+0x54/0xe9 with crng_init=0
[ 47.841839] DLM installed
[ 47.864685] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 47.904608] ntfs: driver 2.1.32 [Flags: R/O].
[ 47.919873] QNX4 filesystem 0.2.3 registered.
[ 47.952844] orangefs_debugfs_init: called with debug mask: :none: :0:
[ 47.975140] orangefs_init: module version upstream loaded
[ 47.992438] JFS: nTxBlock = 2433, nTxLock = 19467
[ 48.036389] SGI XFS with security attributes, realtime, no debug enabled
[ 48.098599] befs: version: 0.9.3
[ 48.140307] ocfs2: Registered cluster interface user
[ 48.155277] OCFS2 User DLM kernel interface loaded
[ 48.274537] gfs2: GFS2 installed
[ 48.343395] NET: Registered protocol family 38
[ 48.343834] cryptomgr_test (180) used greatest stack depth: 30144 bytes left
[ 48.358495] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)
[ 48.379396] io scheduler mq-deadline registered
[ 48.387666] io scheduler kyber registered
[ 53.169014] String selftests succeeded
[ 53.223130] crc32: CRC_LE_BITS = 32, CRC_BE BITS = 32
[ 53.233144] crc32: self tests passed, processed 225944 bytes in 7290431 nsec
[ 53.259779] crc32c: CRC_LE_BITS = 32
[ 53.266937] crc32c: self tests passed, processed 225944 bytes in 3887650 nsec
[ 53.810643] crc32_combine: 8373 self tests passed
[ 54.349828] crc32c_combine: 8373 self tests passed
[ 54.365046] glob: 64 self-tests passed, 0 failed
[ 54.374151] _warn_unseeded_randomness: 15 callbacks suppressed
[ 54.374285] random: get_random_u64 called from cache_random_seq_create+0x54/0xe9 with crng_init=0
[ 54.457206] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 54.459189] kobject: '104-dio-48e' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 54.471689] kobject: '104-idio-16' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 54.492559] kobject: '104-idi-48' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 54.514849] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 54.540904] kobject: 'gpio-mm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 54.564757] gpio_it87: no device
[ 54.584786] gpio_winbond: chip ID at 2e is ffff
[ 54.593981] gpio_winbond: not an our chip
[ 54.602441] gpio_winbond: chip ID at 4e is ffff
[ 54.612166] gpio_winbond: not an our chip
[ 54.623951] kobject: 'gpio_winbond.0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 54.643403] kobject: 'gpio_winbond' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 54.663946] kobject: 'ws16c48' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 54.728301] no IO addresses supplied
[ 54.747101] hgafb: HGA card not detected.
[ 54.755942] hgafb: probe of hgafb.0 failed with error -22
[ 54.774669] usbcore: registered new interface driver udlfb
[ 54.786984] usbcore: registered new interface driver smscufx
[ 54.842791] uvesafb: failed to execute /sbin/v86d
[ 54.842824] kworker/u4:4 (182) used greatest stack depth: 28832 bytes left
[ 54.864705] uvesafb: make sure that the v86d helper is installed and executable
[ 54.903492] uvesafb: Getting VBE info block failed (eax=0x4f00, err=-2)
[ 54.915932] uvesafb: vbe_init() failed with -22
[ 54.924520] uvesafb: probe of uvesafb.0 failed with error -22
[ 54.938292] intel_idle: Please enable MWAIT in BIOS SETUP
[ 54.959196] IPMI message handler: version 39.2
[ 54.969501] ipmi_si: IPMI System Interface driver
[ 54.982697] kobject: 'ipmi_si' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 55.000650] kobject: 'ipmi_si' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 55.039698] ipmi_si: Unable to find any System Interface(s)
[ 55.050675] IPMI Watchdog: driver initialized
[ 55.058560] IPMI poweroff: Copyright (C) 2004 MontaVista Software - IPMI Powerdown via sys_reboot
[ 55.096797] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[ 55.123779] ACPI: Power Button [PWRF]
[ 55.140051] kobject: 'input' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 55.170745] kobject: 'input0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 55.196595] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[ 55.242551] ACPI: Power Button [PWRF]
[ 55.269389] kobject: 'thermal' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 55.291282] kobject: 'cooling_device0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 55.351772] kobject: 'cooling_device1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 55.383521] _warn_unseeded_randomness: 12 callbacks suppressed
[ 55.383591] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 59.258387] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 59.437862] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 59.462534] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 59.490941] kobject: 'serial0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 59.520180] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 59.592155] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 59.660492] kobject: 'tty' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 59.696001] kobject: 'ttyS0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 59.722452] kobject: '(null)' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 59.754500] printk: console [ttyS0] disabled
[ 59.880591] printk: console [ttyS0] enabled
[ 59.923974] kobject: 'serial1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 60.038709] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[ 60.075655] kobject: 'serial2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 60.142118] kobject: 'tty' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 60.161146] kobject: 'ttyS1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 60.180064] kobject: '(null)' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 60.318963] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[ 60.339766] kobject: 'serial3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 60.405738] kobject: 'serial4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 60.443424] kobject: 'serial5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 60.474170] _warn_unseeded_randomness: 10 callbacks suppressed
[ 60.474307] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 60.561185] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 60.582510] kobject: 'ttyS2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 60.606713] kobject: '(null)' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 60.642684] kobject: 'serial6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 60.647445] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 60.689810] kobject: 'ttyS3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 60.706558] kobject: '(null)' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 60.739077] kobject: 'serial7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 60.788361] Non-volatile memory driver v1.3
[ 60.810364] telclk_interrupt = 0xf non-mcpbl0010 hw.
[ 60.823156] kobject: 'telco_clock' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 60.846520] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds).
[ 60.872480] kobject: 'tpm_atmel' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 60.906762] drm_mm: Testing DRM range manger (struct drm_mm), with random_seed=0xb5119d4b max_iterations=8192 max_prime=128
[ 60.927289] drm_mm: igt_sanitycheck - ok!
[ 60.935801] igt_debug 0x0000000000000000-0x0000000000000200: 512: free
[ 60.949387] igt_debug 0x0000000000000200-0x0000000000000600: 1024: used
[ 60.966615] igt_debug 0x0000000000000600-0x0000000000000a00: 1024: free
[ 60.980096] igt_debug 0x0000000000000a00-0x0000000000000e00: 1024: used
[ 60.992778] igt_debug 0x0000000000000e00-0x0000000000001000: 512: free
[ 61.016046] igt_debug total: 4096, used 2048 free 2048
[ 101.750799] Writes: Total: 1264873 Max/Min: 0/0 Fail: 0
[ 163.191383] Writes: Total: 2762560 Max/Min: 0/0 Fail: 0
[ 224.630661] Writes: Total: 4254799 Max/Min: 0/0 Fail: 0
[ 286.070516] Writes: Total: 5762564 Max/Min: 0/0 Fail: 0
[ 347.515145] Writes: Total: 7474930 Max/Min: 0/0 Fail: 0
[ 408.950583] Writes: Total: 9334047 Max/Min: 0/0 Fail: 0
[ 470.390841] Writes: Total: 10816770 Max/Min: 0/0 Fail: 0
[ 531.830342] Writes: Total: 12258160 Max/Min: 0/0 Fail: 0
[ 593.274718] Writes: Total: 13841775 Max/Min: 0/0 Fail: 0
[ 654.710390] Writes: Total: 15530189 Max/Min: 0/0 Fail: 0
[ 716.150948] Writes: Total: 17174432 Max/Min: 0/0 Fail: 0
[ 777.589655] Writes: Total: 18634471 Max/Min: 0/0 Fail: 0
[ 839.030423] Writes: Total: 19930535 Max/Min: 0/0 Fail: 0
[ 900.470380] Writes: Total: 21866713 Max/Min: 0/0 Fail: 0
[ 961.910572] Writes: Total: 23416101 Max/Min: 0/0 Fail: 0
[ 1023.350753] Writes: Total: 25087671 Max/Min: 0/0 Fail: 0
[ 1084.815720] Writes: Total: 26968147 Max/Min: 0/0 Fail: 0
[ 1146.238789] Writes: Total: 28556113 Max/Min: 0/0 Fail: 0
[ 1207.670133] Writes: Total: 30161614 Max/Min: 0/0 Fail: 0
[ 1227.430957] _warn_unseeded_randomness: 10 callbacks suppressed
[ 1227.431029] random: get_random_u64 called from cache_random_seq_create+0x54/0xe9 with crng_init=0
[ 1227.509297] [drm] Initialized vgem 1.0.0 20120112 for vgem on minor 0
[ 1227.512732] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[ 1227.521916] [drm] Driver supports precise vblank timestamp query.
[ 1227.579744] [drm] Initialized vkms 1.0.0 20180514 for vkms on minor 1
[ 1227.653654] dummy-irq: no IRQ given. Use irq=N
[ 1227.656360] Silicon Labs C2 port support v. 0.51.0 - (C) 2007 Rodolfo Giometti
[ 1227.659891] usbcore: registered new interface driver rtsx_usb
[ 1227.687512] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1227.702228] kobject: 'pasic3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1227.722036] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1227.725718] kobject: 'i2c-htcpld' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1227.740900] usbcore: registered new interface driver viperboard
[ 1227.764395] usbcore: registered new interface driver dln2
[ 1227.790368] Uniform Multi-Platform E-IDE driver
[ 1227.805370] ide-gd driver 1.18
[ 1227.957863] Rounding down aligned max_sectors from 4294967295 to 4294967288
[ 1227.978891] db_root: cannot open: /etc/target
[ 1228.022077] SSFDC read-only Flash Translation layer
[ 1228.032115] mtdoops: mtd device (mtddev=name/number) must be supplied
[ 1228.048446] SBC-GXx flash: IO:0x258-0x259 MEM:0xdc000-0xdffff
[ 1228.063739] kobject: 'docg3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1228.347802] No valid DiskOnChip devices found
[ 1228.486417] mdio_bus fixed-0: GPIO lookup for consumer reset
[ 1228.494285] mdio_bus fixed-0: using lookup tables for GPIO lookup
[ 1228.496968] mdio_bus fixed-0: No GPIO consumer reset found
[ 1228.499230] libphy: Fixed MDIO Bus: probed
[ 1228.570909] vcan: Virtual CAN interface driver
[ 1228.579563] vxcan: Virtual CAN Tunnel driver
[ 1228.587666] CAN device driver interface
[ 1228.598263] usbcore: registered new interface driver usb_8dev
[ 1228.600456] usbcore: registered new interface driver ems_usb
[ 1228.602395] usbcore: registered new interface driver esd_usb2
[ 1228.607978] usbcore: registered new interface driver kvaser_usb
[ 1228.620505] usbcore: registered new interface driver mcba_usb
[ 1228.641876] usbcore: registered new interface driver peak_usb
[ 1228.646013] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[ 1228.647500] e1000: Copyright (c) 1999-2006 Intel Corporation.
[ 1258.715512] ACPI Error: Aborting method \_SB.PCI0._PRT due to previous error (AE_AML_LOOP_TIMEOUT) (20190509/psparse-531)
[ 1258.827035] ACPI Error: Method execution failed \_SB.PCI0._PRT due to previous error (AE_AML_LOOP_TIMEOUT) (20190509/uteval-69)
[ 1258.849064] e1000 0000:00:03.0: can't derive routing for PCI INT A
[ 1258.852237] e1000 0000:00:03.0: PCI INT A: no GSI
[ 1260.295572] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[ 1260.299213] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[ 1260.302257] _warn_unseeded_randomness: 9 callbacks suppressed
[ 1260.302276] random: get_random_u64 called from copy_process+0x5b5/0x4642 with crng_init=0
[ 1260.345929] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1260.362101] kobject: 'rx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1260.381279] kobject: 'tx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1260.425515] kobject: 'net' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1264.503035] kobject: 'queues' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1264.810417] kobject: 'eth0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1264.854488] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1269.111685] Writes: Total: 32167303 Max/Min: 0/0 Fail: 0
[ 1295.078859] ACPI Error: Aborting method \_SB.PCI0._PRT due to previous error (AE_AML_LOOP_TIMEOUT) (20190509/psparse-531)
[ 1295.230302] ACPI Error: Method execution failed \_SB.PCI0._PRT due to previous error (AE_AML_LOOP_TIMEOUT) (20190509/uteval-69)
[ 1295.246348] e1000 0000:00:03.0: can't derive routing for PCI INT A
[ 1295.262032] e1000 0000:00:03.0: PCI INT A: no GSI
[ 1296.853071] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[ 1296.870105] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[ 1296.891613] PPP generic driver version 2.4.2
[ 1296.909299] PPP BSD Compression module registered
[ 1296.918922] PPP MPPE Compression module registered
[ 1296.934901] NET: Registered protocol family 24
[ 1296.953919] DLCI driver v0.35, 4 Jan 1997, mike.mclagan@xxxxxxxxxx
[ 1296.966982] usbcore: registered new interface driver ath6kl_usb
[ 1296.985840] usbcore: registered new interface driver usb8xxx
[ 1296.997707] libertas_spi: Libertas SPI driver
[ 1297.015741] usbcore: registered new interface driver zd1201
[ 1297.027612] usbcore: registered new interface driver rndis_wlan
[ 1297.038652] random: get_random_bytes called from virt_wifi_init_module+0x19/0x335 with crng_init=0
[ 1297.074577] usbcore: registered new interface driver kaweth
[ 1297.085020] pegasus: v0.9.3 (2013/04/25), Pegasus/Pegasus II USB Ethernet driver
[ 1297.101966] usbcore: registered new interface driver pegasus
[ 1297.115247] usbcore: registered new interface driver rtl8150
[ 1297.127472] usbcore: registered new interface driver r8152
[ 1297.153116] usbcore: registered new interface driver asix
[ 1297.165203] usbcore: registered new interface driver cdc_ether
[ 1297.178005] usbcore: registered new interface driver dm9601
[ 1297.195773] usbcore: registered new interface driver sr9700
[ 1297.208483] usbcore: registered new interface driver CoreChips
[ 1297.224295] usbcore: registered new interface driver smsc75xx
[ 1297.239637] usbcore: registered new interface driver smsc95xx
[ 1297.267970] usbcore: registered new interface driver gl620a
[ 1297.279827] usbcore: registered new interface driver rndis_host
[ 1297.292883] usbcore: registered new interface driver cdc_subset
[ 1297.307009] usbcore: registered new interface driver MOSCHIP usb-ethernet driver
[ 1297.320563] random: get_random_bytes called from usbnet_init+0x16/0x2b with crng_init=0
[ 1297.336955] usbcore: registered new interface driver int51x1
[ 1297.350667] usbcore: registered new interface driver cdc_phonet
[ 1297.364424] usbcore: registered new interface driver kalmia
[ 1297.376735] usbcore: registered new interface driver ipheth
[ 1297.391449] usbcore: registered new interface driver cx82310_eth
[ 1297.405310] usbcore: registered new interface driver cdc_ncm
[ 1297.418433] usbcore: registered new interface driver qmi_wwan
[ 1297.430871] usbcore: registered new interface driver cdc_mbim
[ 1297.455960] random: get_random_u64 called from cache_random_seq_create+0x54/0xe9 with crng_init=0
[ 1297.484861] usbcore: registered new interface driver hwa-rc
[ 1297.520298] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1297.534057] ehci-pci: EHCI PCI platform driver
[ 1297.546892] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 1297.562120] ohci-pci: OHCI PCI platform driver
[ 1297.572784] ohci-platform: OHCI generic platform driver
[ 1297.588532] ehci-fsl: Freescale EHCI Host controller driver
[ 1297.613126] usbcore: registered new interface driver cdc_wdm
[ 1297.631662] usbcore: registered new interface driver adutux
[ 1297.644660] usbcore: registered new interface driver appledisplay
[ 1297.658606] usbcore: registered new interface driver cytherm
[ 1297.671338] usbcore: registered new interface driver emi26 - firmware loader
[ 1297.689302] usbcore: registered new interface driver idmouse
[ 1297.742674] usbcore: registered new interface driver iowarrior
[ 1297.756651] usbcore: registered new interface driver isight_firmware
[ 1297.770126] usbcore: registered new interface driver legousbtower
[ 1297.783549] usbcore: registered new interface driver rio500
[ 1297.795700] usbcore: registered new interface driver usbtest
[ 1297.814623] usbcore: registered new interface driver trancevibrator
[ 1297.828945] usbcore: registered new interface driver usbsevseg
[ 1297.847289] usbcore: registered new interface driver chaoskey
[ 1297.861102] usbcore: registered new interface driver lvs
[ 1297.902817] vhci_hcd: vhci_hcd_probe:1323: name vhci_hcd id 0
[ 1297.912194] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[ 1297.924606] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 1
[ 1297.939734] vhci_hcd: vhci_start:1155: enter vhci_start
[ 1297.952215] vhci_hcd: vhci_start:1197: created sysfs vhci_hcd.0
[ 1298.026687] hub 1-0:1.0: USB hub found
[ 1298.042135] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2900 wIndex 0
[ 1298.056264] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1298.059592] vhci_hcd: vhci_hub_control:635: port -1
[ 1298.073829] vhci_hcd: vhci_hub_control:643: bye
[ 1298.087061] hub 1-0:1.0: 8 ports detected
[ 1298.100275] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1298.115050] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1298.117631] vhci_hcd: vhci_hub_control:635: port -1
[ 1298.117631] vhci_hcd: vhci_hub_control:643: bye
[ 1298.251770] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1298.265978] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1298.270375] vhci_hcd: vhci_hub_control:635: port 0
[ 1298.270375] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1298.270375] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1298.270375] vhci_hcd: dump_port_status_diff:150:
[ 1298.270375] vhci_hcd: vhci_hub_control:643: bye
[ 1298.338468] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1298.353209] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1298.355952] vhci_hcd: vhci_hub_control:635: port 1
[ 1298.355952] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1298.355952] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1298.355952] vhci_hcd: dump_port_status_diff:150:
[ 1298.355952] vhci_hcd: vhci_hub_control:643: bye
[ 1298.428547] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1298.441417] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1298.445227] vhci_hcd: vhci_hub_control:635: port 2
[ 1298.445227] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1298.445227] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1298.445227] vhci_hcd: dump_port_status_diff:150:
[ 1298.445227] vhci_hcd: vhci_hub_control:643: bye
[ 1298.506709] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1298.530059] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1298.533671] vhci_hcd: vhci_hub_control:635: port 3
[ 1298.533671] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1298.533671] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1298.533671] vhci_hcd: dump_port_status_diff:150:
[ 1298.533671] vhci_hcd: vhci_hub_control:643: bye
[ 1298.647749] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1298.663263] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1298.665618] vhci_hcd: vhci_hub_control:635: port 4
[ 1298.665618] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1298.689237] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1298.689237] vhci_hcd: dump_port_status_diff:150:
[ 1298.689237] vhci_hcd: vhci_hub_control:643: bye
[ 1298.732622] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1298.746838] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1298.749617] vhci_hcd: vhci_hub_control:635: port 5
[ 1298.749617] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1298.771708] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1298.771708] vhci_hcd: dump_port_status_diff:150:
[ 1298.771708] vhci_hcd: vhci_hub_control:643: bye
[ 1298.818849] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1298.836059] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1298.837599] vhci_hcd: vhci_hub_control:635: port 6
[ 1298.837599] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1298.837599] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1298.837599] vhci_hcd: dump_port_status_diff:150:
[ 1298.837599] vhci_hcd: vhci_hub_control:643: bye
[ 1298.908324] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1298.922421] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1298.931954] vhci_hcd: vhci_hub_control:635: port 7
[ 1298.931954] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1298.931954] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1298.931954] vhci_hcd: dump_port_status_diff:150:
[ 1298.931954] vhci_hcd: vhci_hub_control:643: bye
[ 1299.002426] _warn_unseeded_randomness: 1 callbacks suppressed
[ 1299.002497] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1299.030365] kobject: 'usb1-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1299.055090] kobject: 'usb1-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1299.078361] kobject: 'usb1-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1299.089600] kobject: 'usb1-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1299.104459] kobject: 'usb1-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1299.132309] kobject: 'usb1-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1299.149119] kobject: 'usb1-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1299.153436] kobject: 'usb1-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1299.171554] hub 1-0:1.0: USB hub found
[ 1299.180524] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1299.202302] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2900 wIndex 0
[ 1299.227258] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1299.229636] vhci_hcd: vhci_hub_control:635: port -1
[ 1299.229636] vhci_hcd: vhci_hub_control:643: bye
[ 1299.257912] hub 1-0:1.0: 8 ports detected
[ 1299.269346] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1299.283222] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1299.285988] vhci_hcd: vhci_hub_control:635: port -1
[ 1299.285988] vhci_hcd: vhci_hub_control:643: bye
[ 1299.388541] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1299.403088] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1299.405601] vhci_hcd: vhci_hub_control:635: port 0
[ 1299.405601] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1299.405601] vhci_hcd: dump_port_status_diff:142: POWER
[ 1299.405601] vhci_hcd: dump_port_status_diff:150:
[ 1299.405601] vhci_hcd: vhci_hub_control:643: bye
[ 1299.508024] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1299.541847] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1299.554129] vhci_hcd: vhci_hub_control:635: port 1
[ 1299.554129] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1299.570915] vhci_hcd: dump_port_status_diff:142: POWER
[ 1299.570915] vhci_hcd: dump_port_status_diff:150:
[ 1299.570915] vhci_hcd: vhci_hub_control:643: bye
[ 1299.611917] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1299.627273] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1299.629589] vhci_hcd: vhci_hub_control:635: port 2
[ 1299.641918] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1299.658929] vhci_hcd: dump_port_status_diff:142: POWER
[ 1299.658929] vhci_hcd: dump_port_status_diff:150:
[ 1299.658929] vhci_hcd: vhci_hub_control:643: bye
[ 1299.697220] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1299.712303] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1299.714524] vhci_hcd: vhci_hub_control:635: port 3
[ 1299.726534] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1299.746112] vhci_hcd: dump_port_status_diff:142: POWER
[ 1299.746112] vhci_hcd: dump_port_status_diff:150:
[ 1299.761791] vhci_hcd: vhci_hub_control:643: bye
[ 1299.782759] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1299.823395] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1299.825831] vhci_hcd: vhci_hub_control:635: port 4
[ 1299.838398] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1299.838398] vhci_hcd: dump_port_status_diff:142: POWER
[ 1299.865890] vhci_hcd: dump_port_status_diff:150:
[ 1299.865890] vhci_hcd: vhci_hub_control:643: bye
[ 1299.873874] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1299.880489] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1299.884298] vhci_hcd: vhci_hub_control:635: port 5
[ 1299.884298] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1299.884298] vhci_hcd: dump_port_status_diff:142: POWER
[ 1299.884298] vhci_hcd: dump_port_status_diff:150:
[ 1299.884298] vhci_hcd: vhci_hub_control:643: bye
[ 1299.947998] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1299.960764] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1299.964441] vhci_hcd: vhci_hub_control:635: port 6
[ 1299.964441] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1299.964441] vhci_hcd: dump_port_status_diff:142: POWER
[ 1299.964441] vhci_hcd: dump_port_status_diff:150:
[ 1299.964441] vhci_hcd: vhci_hub_control:643: bye
[ 1300.007697] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1300.025223] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1300.025626] vhci_hcd: vhci_hub_control:635: port 7
[ 1300.025626] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1300.025626] vhci_hcd: dump_port_status_diff:142: POWER
[ 1300.025626] vhci_hcd: dump_port_status_diff:150:
[ 1300.025626] vhci_hcd: vhci_hub_control:643: bye
[ 1300.076430] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1300.088011] kobject: 'ep_81' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1300.127540] kobject: 'usb1-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1300.150130] kobject: 'usb1-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1300.175216] kobject: 'usb1-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1300.200786] kobject: 'usb1-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1300.226773] kobject: 'usb1-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1300.248544] kobject: 'usb1-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1300.270526] kobject: 'usb1-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1300.294567] kobject: 'usb1-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1300.314641] hub 1-0:1.0: USB hub found
[ 1300.316207] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2900 wIndex 0
[ 1300.320051] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1300.323591] vhci_hcd: vhci_hub_control:635: port -1
[ 1300.323591] vhci_hcd: vhci_hub_control:643: bye
[ 1300.354783] hub 1-0:1.0: 8 ports detected
[ 1300.366041] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1300.367970] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1300.369491] vhci_hcd: vhci_hub_control:635: port -1
[ 1300.369491] vhci_hcd: vhci_hub_control:643: bye
[ 1300.398306] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1300.499604] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1300.514809] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1300.517611] vhci_hcd: vhci_hub_control:635: port 0
[ 1300.517611] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1300.517611] vhci_hcd: dump_port_status_diff:142: POWER
[ 1300.517611] vhci_hcd: dump_port_status_diff:150:
[ 1300.517611] vhci_hcd: vhci_hub_control:643: bye
[ 1300.613412] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1300.626701] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1300.630853] vhci_hcd: vhci_hub_control:635: port 1
[ 1300.630853] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1300.630853] vhci_hcd: dump_port_status_diff:142: POWER
[ 1300.630853] vhci_hcd: dump_port_status_diff:150:
[ 1300.630853] vhci_hcd: vhci_hub_control:643: bye
[ 1300.673837] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1300.685322] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1300.689090] vhci_hcd: vhci_hub_control:635: port 2
[ 1300.689090] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1300.689090] vhci_hcd: dump_port_status_diff:142: POWER
[ 1300.713604] vhci_hcd: dump_port_status_diff:150:
[ 1300.713604] vhci_hcd: vhci_hub_control:643: bye
[ 1300.718411] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1300.720710] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1300.731272] vhci_hcd: vhci_hub_control:635: port 3
[ 1300.737786] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1300.737786] vhci_hcd: dump_port_status_diff:142: POWER
[ 1300.737786] vhci_hcd: dump_port_status_diff:150:
[ 1300.737786] vhci_hcd: vhci_hub_control:643: bye
[ 1300.770706] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1300.772864] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1300.774134] vhci_hcd: vhci_hub_control:635: port 4
[ 1300.774134] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1300.774134] vhci_hcd: dump_port_status_diff:142: POWER
[ 1300.774134] vhci_hcd: dump_port_status_diff:150:
[ 1300.774134] vhci_hcd: vhci_hub_control:643: bye
[ 1300.815674] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1300.817928] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1300.821894] vhci_hcd: vhci_hub_control:635: port 5
[ 1300.829910] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1300.846250] vhci_hcd: dump_port_status_diff:142: POWER
[ 1300.847017] vhci_hcd: dump_port_status_diff:150:
[ 1300.847017] vhci_hcd: vhci_hub_control:643: bye
[ 1300.878095] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1300.887647] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1300.890694] vhci_hcd: vhci_hub_control:635: port 6
[ 1300.891538] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1300.891538] vhci_hcd: dump_port_status_diff:142: POWER
[ 1300.891538] vhci_hcd: dump_port_status_diff:150:
[ 1300.897792] vhci_hcd: vhci_hub_control:643: bye
[ 1300.915846] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1300.928647] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1300.932480] vhci_hcd: vhci_hub_control:635: port 7
[ 1300.932480] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1300.937582] vhci_hcd: dump_port_status_diff:142: POWER
[ 1300.937582] vhci_hcd: dump_port_status_diff:150:
[ 1300.937582] vhci_hcd: vhci_hub_control:643: bye
[ 1300.951334] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1300.967406] kobject: 'usb1-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1301.022484] kobject: 'usb1-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1301.043389] kobject: 'usb1-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1301.050786] kobject: 'usb1-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1301.054567] kobject: 'usb1-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1301.096644] kobject: 'usb1-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1301.101757] kobject: 'usb1-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1301.105239] kobject: 'usb1-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1301.126679] hub 1-0:1.0: USB hub found
[ 1301.150665] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2900 wIndex 0
[ 1301.152713] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1301.153492] vhci_hcd: vhci_hub_control:635: port -1
[ 1301.153492] vhci_hcd: vhci_hub_control:643: bye
[ 1301.187101] hub 1-0:1.0: 8 ports detected
[ 1301.192090] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1301.194876] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1301.196665] vhci_hcd: vhci_hub_control:635: port -1
[ 1301.198119] vhci_hcd: vhci_hub_control:643: bye
[ 1301.202638] _warn_unseeded_randomness: 2 callbacks suppressed
[ 1301.202728] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1301.299594] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1301.314367] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1301.317620] vhci_hcd: vhci_hub_control:635: port 0
[ 1301.317620] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1301.317620] vhci_hcd: dump_port_status_diff:142: POWER
[ 1301.317620] vhci_hcd: dump_port_status_diff:150:
[ 1301.317620] vhci_hcd: vhci_hub_control:643: bye
[ 1301.382514] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1301.402524] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1301.405616] vhci_hcd: vhci_hub_control:635: port 1
[ 1301.405616] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1301.405616] vhci_hcd: dump_port_status_diff:142: POWER
[ 1301.405616] vhci_hcd: dump_port_status_diff:150:
[ 1301.405616] vhci_hcd: vhci_hub_control:643: bye
[ 1301.471290] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1301.484317] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1301.490740] vhci_hcd: vhci_hub_control:635: port 2
[ 1301.490740] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1301.490740] vhci_hcd: dump_port_status_diff:142: POWER
[ 1301.490740] vhci_hcd: dump_port_status_diff:150:
[ 1301.490740] vhci_hcd: vhci_hub_control:643: bye
[ 1301.554410] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1301.560798] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1301.563547] vhci_hcd: vhci_hub_control:635: port 3
[ 1301.564626] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1301.564626] vhci_hcd: dump_port_status_diff:142: POWER
[ 1301.564626] vhci_hcd: dump_port_status_diff:150:
[ 1301.564626] vhci_hcd: vhci_hub_control:643: bye
[ 1301.598276] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1301.638358] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1301.642086] vhci_hcd: vhci_hub_control:635: port 4
[ 1301.657797] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1301.657797] vhci_hcd: dump_port_status_diff:142: POWER
[ 1301.657797] vhci_hcd: dump_port_status_diff:150:
[ 1301.657797] vhci_hcd: vhci_hub_control:643: bye
[ 1301.687084] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1301.689203] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1301.690346] vhci_hcd: vhci_hub_control:635: port 5
[ 1301.702323] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1301.702323] vhci_hcd: dump_port_status_diff:142: POWER
[ 1301.721798] vhci_hcd: dump_port_status_diff:150:
[ 1301.721798] vhci_hcd: vhci_hub_control:643: bye
[ 1301.760946] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1301.773934] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1301.777580] vhci_hcd: vhci_hub_control:635: port 6
[ 1301.793767] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1301.797886] vhci_hcd: dump_port_status_diff:142: POWER
[ 1301.797886] vhci_hcd: dump_port_status_diff:150:
[ 1301.797886] vhci_hcd: vhci_hub_control:643: bye
[ 1301.840405] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1301.895583] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1301.909947] vhci_hcd: vhci_hub_control:635: port 7
[ 1301.909947] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1301.909947] vhci_hcd: dump_port_status_diff:142: POWER
[ 1301.967112] vhci_hcd: dump_port_status_diff:150:
[ 1301.967112] vhci_hcd: vhci_hub_control:643: bye
[ 1301.976980] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1301.978676] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[ 1301.981211] kobject: '1-0:1.0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1302.010471] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 2
[ 1302.017594] vhci_hcd: vhci_start:1155: enter vhci_start
[ 1302.020070] vhci_hcd: vhci_hub_control:336: typeReq 8006 wValue f00 wIndex 0
[ 1302.023233] vhci_hcd: vhci_hub_control:635: port -1
[ 1302.026195] vhci_hcd: vhci_hub_control:643: bye
[ 1302.046244] vhci_hcd: vhci_hub_control:336: typeReq 8006 wValue f00 wIndex 0
[ 1302.063639] vhci_hcd: vhci_hub_control:635: port -1
[ 1302.065629] vhci_hcd: vhci_hub_control:643: bye
[ 1302.085253] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 1302.100980] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 1
[ 1302.114347] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1302.121855] vhci_hcd: vhci_hub_control:431: GetPortStatus port 1
[ 1302.125603] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1302.125603] vhci_hcd: vhci_hub_control:635: port 0
[ 1302.125603] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1302.125603] vhci_hcd: dump_port_status_diff:142: POWER
[ 1302.125603] vhci_hcd: dump_port_status_diff:150:
[ 1302.125603] vhci_hcd: vhci_hub_control:643: bye
[ 1302.170957] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 2
[ 1302.176401] hub 2-0:1.0: USB hub found
[ 1302.186258] vhci_hcd: vhci_hub_control:431: GetPortStatus port 2
[ 1302.194363] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2a00 wIndex 0
[ 1302.189608] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1302.189608] vhci_hcd: vhci_hub_control:635: port 1
[ 1302.189608] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1302.189608] vhci_hcd: dump_port_status_diff:142: POWER
[ 1302.189608] vhci_hcd: dump_port_status_diff:150:
[ 1302.189608] vhci_hcd: vhci_hub_control:643: bye
[ 1302.204853] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 3
[ 1302.219954] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1302.219992] vhci_hcd: vhci_hub_control:635: port -1
[ 1302.220055] vhci_hcd: vhci_hub_control:643: bye
[ 1302.236275] vhci_hcd: vhci_hub_control:431: GetPortStatus port 3
[ 1302.333801] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1302.345986] vhci_hcd: vhci_hub_control:635: port 2
[ 1302.345986] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1302.364129] hub 2-0:1.0: 8 ports detected
[ 1302.362966] vhci_hcd: dump_port_status_diff:142: POWER
[ 1302.362966] vhci_hcd: dump_port_status_diff:150:
[ 1302.362966] vhci_hcd: vhci_hub_control:643: bye
[ 1302.389512] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 4
[ 1302.396695] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1302.403613] vhci_hcd: vhci_hub_control:431: GetPortStatus port 4
[ 1302.405956] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1302.405956] vhci_hcd: vhci_hub_control:635: port 3
[ 1302.405956] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1302.405956] vhci_hcd: dump_port_status_diff:142: POWER
[ 1302.405956] vhci_hcd: dump_port_status_diff:150:
[ 1302.405956] vhci_hcd: vhci_hub_control:643: bye
[ 1302.477598] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1302.477598] vhci_hcd: vhci_hub_control:635: port -1
[ 1302.477598] vhci_hcd: vhci_hub_control:643: bye
[ 1302.597320] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1302.611708] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1302.614080] vhci_hcd: vhci_hub_control:635: port 0
[ 1302.614080] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1302.614080] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1302.614080] vhci_hcd: dump_port_status_diff:150:
[ 1302.614080] vhci_hcd: vhci_hub_control:643: bye
[ 1302.666854] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 5
[ 1302.677909] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1302.700401] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1302.703977] vhci_hcd: vhci_hub_control:635: port 1
[ 1302.703977] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1302.703977] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1302.703977] vhci_hcd: dump_port_status_diff:150:
[ 1302.703977] vhci_hcd: vhci_hub_control:643: bye
[ 1302.705597] vhci_hcd: vhci_hub_control:431: GetPortStatus port 5
[ 1302.752778] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1302.752343] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1302.752343] vhci_hcd: vhci_hub_control:635: port 4
[ 1302.752343] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1302.752343] vhci_hcd: dump_port_status_diff:142: POWER
[ 1302.752343] vhci_hcd: dump_port_status_diff:150:
[ 1302.752343] vhci_hcd: vhci_hub_control:643: bye
[ 1302.758117] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1302.805226] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 6
[ 1302.804877] vhci_hcd: vhci_hub_control:635: port 2
[ 1302.804877] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1302.804877] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1302.804877] vhci_hcd: dump_port_status_diff:150:
[ 1302.804877] vhci_hcd: vhci_hub_control:643: bye
[ 1302.810704] vhci_hcd: vhci_hub_control:431: GetPortStatus port 6
[ 1302.861125] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1302.858630] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1302.858630] vhci_hcd: vhci_hub_control:635: port 5
[ 1302.858630] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1302.858630] vhci_hcd: dump_port_status_diff:142: POWER
[ 1302.858630] vhci_hcd: dump_port_status_diff:150:
[ 1302.858630] vhci_hcd: vhci_hub_control:643: bye
[ 1302.869776] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 7
[ 1302.878810] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1302.882588] vhci_hcd: vhci_hub_control:635: port 3
[ 1302.882588] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1302.882588] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1302.882588] vhci_hcd: dump_port_status_diff:150:
[ 1302.882588] vhci_hcd: vhci_hub_control:643: bye
[ 1302.895320] vhci_hcd: vhci_hub_control:431: GetPortStatus port 7
[ 1303.022607] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1302.895320] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1302.895320] vhci_hcd: vhci_hub_control:635: port 6
[ 1302.895320] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1302.895320] vhci_hcd: dump_port_status_diff:142: POWER
[ 1302.895320] vhci_hcd: dump_port_status_diff:150:
[ 1302.895320] vhci_hcd: vhci_hub_control:643: bye
[ 1303.030745] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1303.078629] vhci_hcd: vhci_hub_control:635: port 4
[ 1303.078629] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1303.078629] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1303.078629] vhci_hcd: dump_port_status_diff:150:
[ 1303.078629] vhci_hcd: vhci_hub_control:643: bye
[ 1303.148363] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1303.163267] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1303.165652] vhci_hcd: vhci_hub_control:635: port 5
[ 1303.165652] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1303.165652] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1303.165652] vhci_hcd: dump_port_status_diff:150:
[ 1303.165652] vhci_hcd: vhci_hub_control:643: bye
[ 1303.230866] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1303.243665] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1303.247505] vhci_hcd: vhci_hub_control:635: port 6
[ 1303.247505] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1303.278836] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 8
[ 1303.247505] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1303.247505] vhci_hcd: dump_port_status_diff:150:
[ 1303.247505] vhci_hcd: vhci_hub_control:643: bye
[ 1303.296866] vhci_hcd: vhci_hub_control:431: GetPortStatus port 8
[ 1303.323910] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1303.296866] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1303.296866] vhci_hcd: vhci_hub_control:635: port 7
[ 1303.296866] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1303.296866] vhci_hcd: dump_port_status_diff:142: POWER
[ 1303.296866] vhci_hcd: dump_port_status_diff:150:
[ 1303.296866] vhci_hcd: vhci_hub_control:643: bye
[ 1303.335022] usb usb1: vhci_bus_suspend
[ 1303.343708] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1303.346603] vhci_hcd: vhci_hub_control:635: port 7
[ 1303.346603] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1303.346603] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1303.346603] vhci_hcd: dump_port_status_diff:150:
[ 1303.346603] vhci_hcd: vhci_hub_control:643: bye
[ 1303.493067] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1303.514453] kobject: 'usb2-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1303.537134] kobject: 'usb2-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1303.573403] kobject: 'usb2-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1303.596835] kobject: 'usb2-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1303.642326] kobject: 'usb2-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1303.664780] kobject: 'usb2-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1303.688115] kobject: 'usb2-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1303.711638] kobject: 'usb2-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1303.735426] hub 2-0:1.0: USB hub found
[ 1303.743720] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1303.763847] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2a00 wIndex 0
[ 1303.779431] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1303.781621] vhci_hcd: vhci_hub_control:635: port -1
[ 1303.781621] vhci_hcd: vhci_hub_control:643: bye
[ 1303.809896] hub 2-0:1.0: 8 ports detected
[ 1303.821860] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1303.828904] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1303.831103] vhci_hcd: vhci_hub_control:635: port -1
[ 1303.832830] vhci_hcd: vhci_hub_control:643: bye
[ 1303.947640] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1303.963395] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1303.965603] vhci_hcd: vhci_hub_control:635: port 0
[ 1303.978272] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1303.998012] vhci_hcd: dump_port_status_diff:142: POWER
[ 1303.998012] vhci_hcd: dump_port_status_diff:150:
[ 1304.013816] vhci_hcd: vhci_hub_control:643: bye
[ 1304.035297] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1304.075448] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1304.077575] vhci_hcd: vhci_hub_control:635: port 1
[ 1304.090295] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1304.110648] vhci_hcd: dump_port_status_diff:142: POWER
[ 1304.110648] vhci_hcd: dump_port_status_diff:150:
[ 1304.125786] vhci_hcd: vhci_hub_control:643: bye
[ 1304.146997] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1304.162432] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1304.165777] vhci_hcd: vhci_hub_control:635: port 2
[ 1304.177898] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1304.195038] vhci_hcd: dump_port_status_diff:142: POWER
[ 1304.205858] vhci_hcd: dump_port_status_diff:150:
[ 1304.205858] vhci_hcd: vhci_hub_control:643: bye
[ 1304.235017] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1304.250934] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1304.253602] vhci_hcd: vhci_hub_control:635: port 3
[ 1304.269862] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1304.286548] vhci_hcd: dump_port_status_diff:142: POWER
[ 1304.290023] vhci_hcd: dump_port_status_diff:150:
[ 1304.301751] vhci_hcd: vhci_hub_control:643: bye
[ 1304.353300] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1304.367133] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1304.370940] vhci_hcd: vhci_hub_control:635: port 4
[ 1304.382800] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1304.397765] vhci_hcd: dump_port_status_diff:142: POWER
[ 1304.397765] vhci_hcd: dump_port_status_diff:150:
[ 1304.397765] vhci_hcd: vhci_hub_control:643: bye
[ 1304.439209] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1304.455115] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1304.457628] vhci_hcd: vhci_hub_control:635: port 5
[ 1304.469971] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1304.487107] vhci_hcd: dump_port_status_diff:142: POWER
[ 1304.525839] vhci_hcd: dump_port_status_diff:150:
[ 1304.525839] vhci_hcd: vhci_hub_control:643: bye
[ 1304.556841] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1304.574566] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1304.577860] vhci_hcd: vhci_hub_control:635: port 6
[ 1304.590386] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1304.606944] vhci_hcd: dump_port_status_diff:142: POWER
[ 1304.606944] vhci_hcd: dump_port_status_diff:150:
[ 1304.606944] vhci_hcd: vhci_hub_control:643: bye
[ 1304.631806] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1304.634270] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1304.636477] vhci_hcd: vhci_hub_control:635: port 7
[ 1304.638233] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1304.638233] vhci_hcd: dump_port_status_diff:142: POWER
[ 1304.638233] vhci_hcd: dump_port_status_diff:150:
[ 1304.638233] vhci_hcd: vhci_hub_control:643: bye
[ 1304.696877] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1304.712982] kobject: 'ep_81' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1304.750666] kobject: 'usb2-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1304.773448] kobject: 'usb2-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1304.797439] kobject: 'usb2-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1304.821447] kobject: 'usb2-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1304.844937] kobject: 'usb2-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1304.869327] kobject: 'usb2-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1304.892950] kobject: 'usb2-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1304.918396] kobject: 'usb2-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1304.947474] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1304.975819] hub 2-0:1.0: USB hub found
[ 1304.986102] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2a00 wIndex 0
[ 1305.000999] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1305.004357] vhci_hcd: vhci_hub_control:635: port -1
[ 1305.004357] vhci_hcd: vhci_hub_control:643: bye
[ 1305.034510] hub 2-0:1.0: 8 ports detected
[ 1305.046443] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1305.054758] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1305.057131] vhci_hcd: vhci_hub_control:635: port -1
[ 1305.058711] vhci_hcd: vhci_hub_control:643: bye
[ 1305.123368] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1305.137983] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1305.141598] vhci_hcd: vhci_hub_control:635: port 0
[ 1305.141598] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1305.141598] vhci_hcd: dump_port_status_diff:142: POWER
[ 1305.141598] vhci_hcd: dump_port_status_diff:150:
[ 1305.141598] vhci_hcd: vhci_hub_control:643: bye
[ 1305.216507] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1305.230548] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1305.238806] vhci_hcd: vhci_hub_control:635: port 1
[ 1305.238806] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1305.238806] vhci_hcd: dump_port_status_diff:142: POWER
[ 1305.238806] vhci_hcd: dump_port_status_diff:150:
[ 1305.238806] vhci_hcd: vhci_hub_control:643: bye
[ 1305.301851] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1305.304603] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1305.305522] vhci_hcd: vhci_hub_control:635: port 2
[ 1305.305522] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1305.305522] vhci_hcd: dump_port_status_diff:142: POWER
[ 1305.305522] vhci_hcd: dump_port_status_diff:150:
[ 1305.305522] vhci_hcd: vhci_hub_control:643: bye
[ 1305.380262] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1305.466066] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1305.469647] vhci_hcd: vhci_hub_control:635: port 3
[ 1305.469647] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1305.469647] vhci_hcd: dump_port_status_diff:142: POWER
[ 1305.469647] vhci_hcd: dump_port_status_diff:150:
[ 1305.469647] vhci_hcd: vhci_hub_control:643: bye
[ 1305.502119] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1305.503922] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1305.505513] vhci_hcd: vhci_hub_control:635: port 4
[ 1305.505513] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1305.505513] vhci_hcd: dump_port_status_diff:142: POWER
[ 1305.505513] vhci_hcd: dump_port_status_diff:150:
[ 1305.505513] vhci_hcd: vhci_hub_control:643: bye
[ 1305.547077] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1305.549074] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1305.550712] vhci_hcd: vhci_hub_control:635: port 5
[ 1305.550712] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1305.550712] vhci_hcd: dump_port_status_diff:142: POWER
[ 1305.550712] vhci_hcd: dump_port_status_diff:150:
[ 1305.550712] vhci_hcd: vhci_hub_control:643: bye
[ 1305.605341] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1305.618920] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1305.622010] vhci_hcd: vhci_hub_control:635: port 6
[ 1305.622010] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1305.622010] vhci_hcd: dump_port_status_diff:142: POWER
[ 1305.622010] vhci_hcd: dump_port_status_diff:150:
[ 1305.622010] vhci_hcd: vhci_hub_control:643: bye
[ 1305.687746] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1305.700767] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1305.704384] vhci_hcd: vhci_hub_control:635: port 7
[ 1305.704384] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1305.704384] vhci_hcd: dump_port_status_diff:142: POWER
[ 1305.704384] vhci_hcd: dump_port_status_diff:150:
[ 1305.704384] vhci_hcd: vhci_hub_control:643: bye
[ 1305.746557] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1305.755134] kobject: 'usb2-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1305.793336] kobject: 'usb2-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1305.802814] kobject: 'usb2-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1305.806759] kobject: 'usb2-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1305.815348] kobject: 'usb2-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1305.838484] kobject: 'usb2-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1305.873389] kobject: 'usb2-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1305.894518] kobject: 'usb2-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1305.899265] hub 2-0:1.0: USB hub found
[ 1305.900542] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1305.914280] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2a00 wIndex 0
[ 1305.928731] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1305.939018] vhci_hcd: vhci_hub_control:635: port -1
[ 1305.941866] vhci_hcd: vhci_hub_control:643: bye
[ 1305.986128] hub 2-0:1.0: 8 ports detected
[ 1305.998344] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1306.012045] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1306.017763] vhci_hcd: vhci_hub_control:635: port -1
[ 1306.025801] vhci_hcd: vhci_hub_control:643: bye
[ 1306.137310] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1306.156489] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1306.157605] vhci_hcd: vhci_hub_control:635: port 0
[ 1306.157605] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1306.157605] vhci_hcd: dump_port_status_diff:142: POWER
[ 1306.157605] vhci_hcd: dump_port_status_diff:150:
[ 1306.157605] vhci_hcd: vhci_hub_control:643: bye
[ 1306.224806] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1306.237891] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1306.241703] vhci_hcd: vhci_hub_control:635: port 1
[ 1306.241703] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1306.241703] vhci_hcd: dump_port_status_diff:142: POWER
[ 1306.241703] vhci_hcd: dump_port_status_diff:150:
[ 1306.241703] vhci_hcd: vhci_hub_control:643: bye
[ 1306.304751] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1306.318424] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1306.321806] vhci_hcd: vhci_hub_control:635: port 2
[ 1306.321806] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1306.321806] vhci_hcd: dump_port_status_diff:142: POWER
[ 1306.321806] vhci_hcd: dump_port_status_diff:150:
[ 1306.321806] vhci_hcd: vhci_hub_control:643: bye
[ 1306.386352] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1306.400635] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1306.402989] vhci_hcd: vhci_hub_control:635: port 3
[ 1306.402989] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1306.402989] vhci_hcd: dump_port_status_diff:142: POWER
[ 1306.402989] vhci_hcd: dump_port_status_diff:150:
[ 1306.402989] vhci_hcd: vhci_hub_control:643: bye
[ 1306.468567] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1306.481426] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1306.485263] vhci_hcd: vhci_hub_control:635: port 4
[ 1306.485263] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1306.485263] vhci_hcd: dump_port_status_diff:142: POWER
[ 1306.485263] vhci_hcd: dump_port_status_diff:150:
[ 1306.485263] vhci_hcd: vhci_hub_control:643: bye
[ 1306.549119] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1306.563549] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1306.565957] vhci_hcd: vhci_hub_control:635: port 5
[ 1306.565957] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1306.565957] vhci_hcd: dump_port_status_diff:142: POWER
[ 1306.565957] vhci_hcd: dump_port_status_diff:150:
[ 1306.565957] vhci_hcd: vhci_hub_control:643: bye
[ 1306.633997] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1306.647828] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1306.650864] vhci_hcd: vhci_hub_control:635: port 6
[ 1306.650864] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1306.650864] vhci_hcd: dump_port_status_diff:142: POWER
[ 1306.650864] vhci_hcd: dump_port_status_diff:150:
[ 1306.650864] vhci_hcd: vhci_hub_control:643: bye
[ 1306.708573] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1306.711194] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1306.713471] vhci_hcd: vhci_hub_control:635: port 7
[ 1306.714799] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1306.715156] vhci_hcd: dump_port_status_diff:142: POWER
[ 1306.715156] vhci_hcd: dump_port_status_diff:150:
[ 1306.715156] vhci_hcd: vhci_hub_control:643: bye
[ 1306.754840] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1306.755538] vhci_hcd: vhci_hcd_probe:1360: bye
[ 1306.758926] kobject: '2-0:1.0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1306.776454] vhci_hcd vhci_hcd.0: remove, state 1
[ 1306.786884] usb usb2: USB disconnect, device number 1
[ 1306.799815] kobject: 'ep_81' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1306.846224] kobject: 'usb2-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1306.867363] kobject: 'usb2-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1306.889240] kobject: 'usb2-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1306.900214] kobject: 'usb2-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1306.904356] kobject: 'usb2-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1306.920840] kobject: 'usb2-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1306.946084] kobject: 'usb2-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1306.963129] kobject: 'usb2-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1306.979629] kobject: 'ep_00' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1306.999043] kobject: '2-0:1.0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1307.054008] vhci_hcd: vhci_stop:1208: stop VHCI controller
[ 1307.066701] usbip_core: event_handler:71: pending event 7
[ 1307.102122] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1307.114509] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1307.130314] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1307.142988] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 0
[ 1307.155239] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1307.178246] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1307.192436] usbip_core: event_handler:71: pending event 7
[ 1307.203457] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1307.206534] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1307.215737] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1307.241216] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 1
[ 1307.255242] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1307.269078] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1307.295822] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1307.302392] usbip_core: event_handler:71: pending event 7
[ 1307.321033] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1307.333278] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1307.346238] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 2
[ 1307.359101] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1307.372653] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1307.384801] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1307.385093] usbip_core: event_handler:71: pending event 7
[ 1307.410043] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1307.422396] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1307.434713] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 3
[ 1307.447118] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1307.460536] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1307.472855] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1307.473109] usbip_core: event_handler:71: pending event 7
[ 1307.496544] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1307.506939] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1307.518474] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 4
[ 1307.530926] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1307.544469] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1307.558244] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1307.559662] usbip_core: event_handler:71: pending event 7
[ 1307.582125] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1307.594468] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1307.606885] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 5
[ 1307.619276] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1307.634038] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1307.646964] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1307.647320] usbip_core: event_handler:71: pending event 7
[ 1307.713825] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1307.726009] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1307.738899] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 6
[ 1307.751575] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1307.780095] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1307.795555] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1307.795837] usbip_core: event_handler:71: pending event 7
[ 1307.804375] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1307.806649] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1307.808643] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 7
[ 1307.831568] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1307.844515] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1307.866250] vhci_hcd vhci_hcd.0: USB bus 2 deregistered
[ 1307.877457] vhci_hcd vhci_hcd.0: remove, state 4
[ 1307.886860] usb usb1: USB disconnect, device number 1
[ 1307.891658] kobject: 'ep_81' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1307.898289] kobject: 'usb1-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1307.912677] kobject: 'usb1-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1307.937299] kobject: 'usb1-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1307.947558] kobject: 'usb1-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1307.951384] kobject: 'usb1-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1307.962930] kobject: 'usb1-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1308.000534] kobject: 'usb1-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1308.028442] kobject: 'usb1-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1308.038820] kobject: '1-0:1.0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1308.042634] kobject: 'ep_00' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1308.065378] vhci_hcd: vhci_stop:1208: stop VHCI controller
[ 1308.098526] usbip_core: event_handler:71: pending event 7
[ 1308.109770] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1308.123705] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1308.136348] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1308.163507] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 0
[ 1308.176111] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1308.189458] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1308.202650] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1308.245943] usbip_core: event_handler:71: pending event 7
[ 1308.256579] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1308.267786] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1308.274785] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 1
[ 1308.277360] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1308.279571] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1308.281421] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1308.290787] usbip_core: event_handler:71: pending event 7
[ 1308.307347] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1308.321834] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1308.329621] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 2
[ 1308.331534] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1308.337003] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1308.350482] usbip_core: event_handler:71: pending event 7
[ 1308.363090] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1308.375693] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1308.387808] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1308.399705] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 3
[ 1308.411661] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1308.422855] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1308.434805] usbip_core: event_handler:71: pending event 7
[ 1308.446771] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1308.457246] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1308.460554] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 4
[ 1308.462864] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1308.466415] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1308.493106] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1308.600858] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1308.601109] usbip_core: event_handler:71: pending event 7
[ 1308.625387] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1308.636564] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1308.649434] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 5
[ 1308.660908] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1308.673504] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1308.684816] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1308.686475] usbip_core: event_handler:71: pending event 7
[ 1308.691771] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1308.693852] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1308.695724] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 6
[ 1308.705855] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1308.718620] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1308.743829] usbip_core: usbip_stop_eh:114: usbip_eh waiting completion 5
[ 1308.744067] usbip_core: event_handler:71: pending event 7
[ 1308.768469] vhci_hcd: vhci_shutdown_connection:1018: stop threads
[ 1308.779845] vhci_hcd: vhci_shutdown_connection:1026: release socket
[ 1308.792518] vhci_hcd: rh_port_disconnect:195: rh_port_disconnect 7
[ 1308.809774] vhci_hcd: vhci_shutdown_connection:1052: disconnect device
[ 1308.823339] usbip_core: usbip_stop_eh:117: usbip_eh has stopped
[ 1308.835588] vhci_hcd vhci_hcd.0: USB bus 1 deregistered
[ 1308.848977] vhci_hcd: vhci_hcd_probe:1323: name vhci_hcd id 0
[ 1308.853696] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[ 1308.856090] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 1
[ 1308.858705] vhci_hcd: vhci_start:1155: enter vhci_start
[ 1308.867831] vhci_hcd: vhci_start:1197: created sysfs vhci_hcd.0
[ 1308.894576] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1308.989297] hub 1-0:1.0: USB hub found
[ 1309.004334] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2900 wIndex 0
[ 1309.019690] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1309.021679] vhci_hcd: vhci_hub_control:635: port -1
[ 1309.038777] vhci_hcd: vhci_hub_control:643: bye
[ 1309.056346] hub 1-0:1.0: 8 ports detected
[ 1309.068006] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1309.081324] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1309.085764] vhci_hcd: vhci_hub_control:635: port -1
[ 1309.085764] vhci_hcd: vhci_hub_control:643: bye
[ 1309.173973] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1309.188044] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1309.189784] vhci_hcd: vhci_hub_control:635: port 0
[ 1309.189784] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1309.189784] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1309.189784] vhci_hcd: dump_port_status_diff:150:
[ 1309.189784] vhci_hcd: vhci_hub_control:643: bye
[ 1309.228889] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1309.231782] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1309.235615] vhci_hcd: vhci_hub_control:635: port 1
[ 1309.235615] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1309.235615] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1309.235615] vhci_hcd: dump_port_status_diff:150:
[ 1309.235615] vhci_hcd: vhci_hub_control:643: bye
[ 1309.300764] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1309.313796] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1309.325856] vhci_hcd: vhci_hub_control:635: port 2
[ 1309.333824] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1309.349862] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1309.357904] vhci_hcd: dump_port_status_diff:150:
[ 1309.365867] vhci_hcd: vhci_hub_control:643: bye
[ 1309.385382] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1309.418083] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1309.421563] vhci_hcd: vhci_hub_control:635: port 3
[ 1309.433926] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1309.465910] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1309.473883] vhci_hcd: dump_port_status_diff:150:
[ 1309.473883] vhci_hcd: vhci_hub_control:643: bye
[ 1309.502234] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1309.515649] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1309.523004] vhci_hcd: vhci_hub_control:635: port 4
[ 1309.523004] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1309.523004] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1309.523004] vhci_hcd: dump_port_status_diff:150:
[ 1309.523004] vhci_hcd: vhci_hub_control:643: bye
[ 1309.563798] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1309.578769] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1309.599057] vhci_hcd: vhci_hub_control:635: port 5
[ 1309.606030] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1309.609567] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1309.609567] vhci_hcd: dump_port_status_diff:150:
[ 1309.613546] vhci_hcd: vhci_hub_control:643: bye
[ 1309.620941] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1309.633868] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1309.637705] vhci_hcd: vhci_hub_control:635: port 6
[ 1309.637705] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1309.637705] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1309.637705] vhci_hcd: dump_port_status_diff:150:
[ 1309.637705] vhci_hcd: vhci_hub_control:643: bye
[ 1309.708301] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1309.725765] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1309.729579] vhci_hcd: vhci_hub_control:635: port 7
[ 1309.729579] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000100
[ 1309.729579] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1309.773859] vhci_hcd: dump_port_status_diff:150:
[ 1309.773859] vhci_hcd: vhci_hub_control:643: bye
[ 1309.797223] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1309.811291] kobject: 'usb1-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1309.834175] kobject: 'usb1-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1309.837429] kobject: 'usb1-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1309.874548] kobject: 'usb1-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1309.904622] kobject: 'usb1-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1309.926274] kobject: 'usb1-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1309.963511] kobject: 'usb1-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1309.992415] kobject: 'usb1-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1310.016915] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1310.042967] hub 1-0:1.0: USB hub found
[ 1310.056351] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2900 wIndex 0
[ 1310.065358] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1310.067750] vhci_hcd: vhci_hub_control:635: port -1
[ 1310.069300] vhci_hcd: vhci_hub_control:643: bye
[ 1310.071226] hub 1-0:1.0: 8 ports detected
[ 1310.073819] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1310.086683] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1310.095424] vhci_hcd: vhci_hub_control:635: port -1
[ 1310.102073] vhci_hcd: vhci_hub_control:643: bye
[ 1310.177288] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1310.187408] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1310.190453] vhci_hcd: vhci_hub_control:635: port 0
[ 1310.191340] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1310.193587] vhci_hcd: dump_port_status_diff:142: POWER
[ 1310.193587] vhci_hcd: dump_port_status_diff:150:
[ 1310.193587] vhci_hcd: vhci_hub_control:643: bye
[ 1310.223983] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1310.232796] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1310.236070] vhci_hcd: vhci_hub_control:635: port 1
[ 1310.236743] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1310.236743] vhci_hcd: dump_port_status_diff:142: POWER
[ 1310.236743] vhci_hcd: dump_port_status_diff:150:
[ 1310.251125] vhci_hcd: vhci_hub_control:643: bye
[ 1310.272454] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1310.286383] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1310.289610] vhci_hcd: vhci_hub_control:635: port 2
[ 1310.289610] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1310.289610] vhci_hcd: dump_port_status_diff:142: POWER
[ 1310.289610] vhci_hcd: dump_port_status_diff:150:
[ 1310.289610] vhci_hcd: vhci_hub_control:643: bye
[ 1310.308125] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1310.321964] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1310.326985] vhci_hcd: vhci_hub_control:635: port 3
[ 1310.343250] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1310.357983] vhci_hcd: dump_port_status_diff:142: POWER
[ 1310.365857] vhci_hcd: dump_port_status_diff:150:
[ 1310.373865] vhci_hcd: vhci_hub_control:643: bye
[ 1310.390067] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1310.403386] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1310.409880] vhci_hcd: vhci_hub_control:635: port 4
[ 1310.409880] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1310.409880] vhci_hcd: dump_port_status_diff:142: POWER
[ 1310.409880] vhci_hcd: dump_port_status_diff:150:
[ 1310.409880] vhci_hcd: vhci_hub_control:643: bye
[ 1310.471966] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1310.485057] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1310.488864] vhci_hcd: vhci_hub_control:635: port 5
[ 1310.488864] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1310.488864] vhci_hcd: dump_port_status_diff:142: POWER
[ 1310.526109] vhci_hcd: dump_port_status_diff:150:
[ 1310.526109] vhci_hcd: vhci_hub_control:643: bye
[ 1310.563774] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1310.584127] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1310.589793] vhci_hcd: vhci_hub_control:635: port 6
[ 1310.589793] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1310.589793] vhci_hcd: dump_port_status_diff:142: POWER
[ 1310.589793] vhci_hcd: dump_port_status_diff:150:
[ 1310.589793] vhci_hcd: vhci_hub_control:643: bye
[ 1310.617942] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1310.629356] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1310.632872] vhci_hcd: vhci_hub_control:635: port 7
[ 1310.632872] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1310.632872] vhci_hcd: dump_port_status_diff:142: POWER
[ 1310.632872] vhci_hcd: dump_port_status_diff:150:
[ 1310.632872] vhci_hcd: vhci_hub_control:643: bye
[ 1310.695501] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1310.697969] kobject: 'ep_81' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1310.718433] kobject: 'usb1-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1310.739622] kobject: 'usb1-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1310.764287] kobject: 'usb1-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1310.787938] kobject: 'usb1-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1310.809006] kobject: 'usb1-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1310.831560] kobject: 'usb1-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1310.859105] kobject: 'usb1-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1310.896406] kobject: 'usb1-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1310.935556] hub 1-0:1.0: USB hub found
[ 1310.943657] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1310.963610] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2900 wIndex 0
[ 1311.023207] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1311.025624] vhci_hcd: vhci_hub_control:635: port -1
[ 1311.025624] vhci_hcd: vhci_hub_control:643: bye
[ 1311.053961] hub 1-0:1.0: 8 ports detected
[ 1311.065923] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1311.078182] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1311.080640] vhci_hcd: vhci_hub_control:635: port -1
[ 1311.081898] vhci_hcd: vhci_hub_control:643: bye
[ 1311.093977] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1311.106350] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1311.135127] kobject: 'usb2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1311.157386] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1311.171879] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1311.174415] vhci_hcd: vhci_hub_control:635: port 0
[ 1311.174415] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1311.174415] vhci_hcd: dump_port_status_diff:142: POWER
[ 1311.174415] vhci_hcd: dump_port_status_diff:150:
[ 1311.174415] vhci_hcd: vhci_hub_control:643: bye
[ 1311.210018] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1311.215307] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1311.219078] vhci_hcd: vhci_hub_control:635: port 1
[ 1311.219078] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1311.219078] vhci_hcd: dump_port_status_diff:142: POWER
[ 1311.219078] vhci_hcd: dump_port_status_diff:150:
[ 1311.219078] vhci_hcd: vhci_hub_control:643: bye
[ 1311.255794] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1311.259820] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1311.263629] vhci_hcd: vhci_hub_control:635: port 2
[ 1311.263629] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1311.263629] vhci_hcd: dump_port_status_diff:142: POWER
[ 1311.263629] vhci_hcd: dump_port_status_diff:150:
[ 1311.263629] vhci_hcd: vhci_hub_control:643: bye
[ 1311.320962] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1311.334915] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1311.338520] vhci_hcd: vhci_hub_control:635: port 3
[ 1311.338520] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1311.344294] vhci_hcd: dump_port_status_diff:142: POWER
[ 1311.344294] vhci_hcd: dump_port_status_diff:150:
[ 1311.344294] vhci_hcd: vhci_hub_control:643: bye
[ 1311.367045] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1311.380688] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1311.384517] vhci_hcd: vhci_hub_control:635: port 4
[ 1311.384517] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1311.384517] vhci_hcd: dump_port_status_diff:142: POWER
[ 1311.384517] vhci_hcd: dump_port_status_diff:150:
[ 1311.384517] vhci_hcd: vhci_hub_control:643: bye
[ 1311.410580] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1311.425327] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1311.438321] vhci_hcd: vhci_hub_control:635: port 5
[ 1311.438321] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1311.438321] vhci_hcd: dump_port_status_diff:142: POWER
[ 1311.438321] vhci_hcd: dump_port_status_diff:150:
[ 1311.438321] vhci_hcd: vhci_hub_control:643: bye
[ 1311.478312] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1311.485616] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1311.488070] vhci_hcd: vhci_hub_control:635: port 6
[ 1311.489518] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1311.489534] vhci_hcd: dump_port_status_diff:142: POWER
[ 1311.517898] vhci_hcd: dump_port_status_diff:150:
[ 1311.517898] vhci_hcd: vhci_hub_control:643: bye
[ 1311.532208] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1311.534533] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1311.542382] vhci_hcd: vhci_hub_control:635: port 7
[ 1311.542382] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1311.542382] vhci_hcd: dump_port_status_diff:142: POWER
[ 1311.542382] vhci_hcd: dump_port_status_diff:150:
[ 1311.542382] vhci_hcd: vhci_hub_control:643: bye
[ 1311.614454] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1311.628864] kobject: 'usb1-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1311.633827] kobject: 'usb1-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1311.637227] kobject: 'usb1-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1311.656933] kobject: 'usb1-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1311.682163] kobject: 'usb1-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1311.686115] kobject: 'usb1-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1311.704041] kobject: 'usb1-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1311.727400] kobject: 'usb1-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1311.754576] hub 1-0:1.0: USB hub found
[ 1311.756398] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2900 wIndex 0
[ 1311.758619] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1311.762343] vhci_hcd: vhci_hub_control:635: port -1
[ 1311.762585] vhci_hcd: vhci_hub_control:643: bye
[ 1311.783305] hub 1-0:1.0: 8 ports detected
[ 1311.795834] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1311.799200] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1311.801192] vhci_hcd: vhci_hub_control:635: port -1
[ 1311.802755] vhci_hcd: vhci_hub_control:643: bye
[ 1311.846684] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1311.848677] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1311.849499] vhci_hcd: vhci_hub_control:635: port 0
[ 1311.849499] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1311.849499] vhci_hcd: dump_port_status_diff:142: POWER
[ 1311.849499] vhci_hcd: dump_port_status_diff:150:
[ 1311.849499] vhci_hcd: vhci_hub_control:643: bye
[ 1311.890855] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1311.893151] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1311.894327] vhci_hcd: vhci_hub_control:635: port 1
[ 1311.897790] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1311.897790] vhci_hcd: dump_port_status_diff:142: POWER
[ 1311.897790] vhci_hcd: dump_port_status_diff:150:
[ 1311.897790] vhci_hcd: vhci_hub_control:643: bye
[ 1311.938077] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1311.963772] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1311.967444] vhci_hcd: vhci_hub_control:635: port 2
[ 1311.967444] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1311.967444] vhci_hcd: dump_port_status_diff:142: POWER
[ 1311.967444] vhci_hcd: dump_port_status_diff:150:
[ 1311.967444] vhci_hcd: vhci_hub_control:643: bye
[ 1312.000680] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1312.017821] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1312.029875] vhci_hcd: vhci_hub_control:635: port 3
[ 1312.037848] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1312.041589] vhci_hcd: dump_port_status_diff:142: POWER
[ 1312.045560] vhci_hcd: dump_port_status_diff:150:
[ 1312.045560] vhci_hcd: vhci_hub_control:643: bye
[ 1312.049212] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1312.064951] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1312.068014] vhci_hcd: vhci_hub_control:635: port 4
[ 1312.068014] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1312.068014] vhci_hcd: dump_port_status_diff:142: POWER
[ 1312.068014] vhci_hcd: dump_port_status_diff:150:
[ 1312.068014] vhci_hcd: vhci_hub_control:643: bye
[ 1312.106906] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1312.108843] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1312.109523] vhci_hcd: vhci_hub_control:635: port 5
[ 1312.109523] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1312.109523] vhci_hcd: dump_port_status_diff:142: POWER
[ 1312.109523] vhci_hcd: dump_port_status_diff:150:
[ 1312.109523] vhci_hcd: vhci_hub_control:643: bye
[ 1312.248083] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1312.253873] _warn_unseeded_randomness: 1 callbacks suppressed
[ 1312.253947] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1312.261429] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1312.273225] kobject: 'usb1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1312.272866] vhci_hcd: vhci_hub_control:635: port 6
[ 1312.272866] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1312.272866] vhci_hcd: dump_port_status_diff:142: POWER
[ 1312.272866] vhci_hcd: dump_port_status_diff:150:
[ 1312.272866] vhci_hcd: vhci_hub_control:643: bye
[ 1312.290338] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1312.362777] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1312.365512] vhci_hcd: vhci_hub_control:635: port 7
[ 1312.379242] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1312.393956] vhci_hcd: dump_port_status_diff:142: POWER
[ 1312.401857] vhci_hcd: dump_port_status_diff:150:
[ 1312.413853] vhci_hcd: vhci_hub_control:643: bye
[ 1312.423285] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1312.428118] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1312.428760] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[ 1312.432080] kobject: '1-0:1.0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1312.442748] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 2
[ 1312.489263] vhci_hcd: vhci_start:1155: enter vhci_start
[ 1312.528448] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 1
[ 1312.551749] vhci_hcd: vhci_hub_control:336: typeReq 8006 wValue f00 wIndex 0
[ 1312.559446] vhci_hcd: vhci_hub_control:431: GetPortStatus port 1
[ 1312.561624] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1312.561624] vhci_hcd: vhci_hub_control:635: port 0
[ 1312.561624] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1312.561624] vhci_hcd: dump_port_status_diff:142: POWER
[ 1312.561624] vhci_hcd: dump_port_status_diff:150:
[ 1312.561624] vhci_hcd: vhci_hub_control:643: bye
[ 1312.577591] vhci_hcd: vhci_hub_control:635: port -1
[ 1312.643565] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 2
[ 1312.640387] vhci_hcd: vhci_hub_control:643: bye
[ 1312.652072] vhci_hcd: vhci_hub_control:336: typeReq 8006 wValue f00 wIndex 0
[ 1312.654078] vhci_hcd: vhci_hub_control:431: GetPortStatus port 2
[ 1312.657980] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1312.657980] vhci_hcd: vhci_hub_control:635: port 1
[ 1312.657980] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1312.657980] vhci_hcd: dump_port_status_diff:142: POWER
[ 1312.657980] vhci_hcd: dump_port_status_diff:150:
[ 1312.657980] vhci_hcd: vhci_hub_control:643: bye
[ 1312.662417] vhci_hcd: vhci_hub_control:635: port -1
[ 1312.736687] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 3
[ 1312.735487] vhci_hcd: vhci_hub_control:643: bye
[ 1312.745573] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 1312.752005] vhci_hcd: vhci_hub_control:431: GetPortStatus port 3
[ 1312.755693] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1312.761749] vhci_hcd: vhci_hub_control:635: port 2
[ 1312.761749] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1312.781256] hub 2-0:1.0: USB hub found
[ 1312.761749] vhci_hcd: dump_port_status_diff:142: POWER
[ 1312.761749] vhci_hcd: dump_port_status_diff:150:
[ 1312.761749] vhci_hcd: vhci_hub_control:643: bye
[ 1312.797012] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 4
[ 1312.805793] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2a00 wIndex 0
[ 1312.812234] vhci_hcd: vhci_hub_control:431: GetPortStatus port 4
[ 1312.815397] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1312.815397] vhci_hcd: vhci_hub_control:635: port 3
[ 1312.815397] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1312.815397] vhci_hcd: dump_port_status_diff:142: POWER
[ 1312.815397] vhci_hcd: dump_port_status_diff:150:
[ 1312.815397] vhci_hcd: vhci_hub_control:643: bye
[ 1312.823990] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1312.931944] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 5
[ 1312.930034] vhci_hcd: vhci_hub_control:635: port -1
[ 1312.930034] vhci_hcd: vhci_hub_control:643: bye
[ 1312.940085] hub 2-0:1.0: 8 ports detected
[ 1312.946175] vhci_hcd: vhci_hub_control:431: GetPortStatus port 5
[ 1312.948785] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1312.949648] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1312.949648] vhci_hcd: vhci_hub_control:635: port 4
[ 1312.949648] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1312.949648] vhci_hcd: dump_port_status_diff:142: POWER
[ 1312.949648] vhci_hcd: dump_port_status_diff:150:
[ 1312.949648] vhci_hcd: vhci_hub_control:643: bye
[ 1312.953747] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1313.024811] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 6
[ 1313.023454] vhci_hcd: vhci_hub_control:635: port -1
[ 1313.023454] vhci_hcd: vhci_hub_control:643: bye
[ 1313.070081] vhci_hcd: vhci_hub_control:431: GetPortStatus port 6
[ 1313.073624] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1313.073624] vhci_hcd: vhci_hub_control:635: port 5
[ 1313.100126] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1313.073624] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1313.073624] vhci_hcd: dump_port_status_diff:142: POWER
[ 1313.073624] vhci_hcd: dump_port_status_diff:150:
[ 1313.073624] vhci_hcd: vhci_hub_control:643: bye
[ 1313.118053] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1313.157225] vhci_hcd: vhci_hub_control:635: port 0
[ 1313.176215] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 7
[ 1313.174725] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1313.174725] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1313.188813] vhci_hcd: dump_port_status_diff:150:
[ 1313.188813] vhci_hcd: vhci_hub_control:643: bye
[ 1313.192654] vhci_hcd: vhci_hub_control:431: GetPortStatus port 7
[ 1313.192654] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1313.192654] vhci_hcd: vhci_hub_control:635: port 6
[ 1313.192654] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1313.192654] vhci_hcd: dump_port_status_diff:142: POWER
[ 1313.192654] vhci_hcd: dump_port_status_diff:150:
[ 1313.192654] vhci_hcd: vhci_hub_control:643: bye
[ 1313.269440] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1313.283379] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1313.284252] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 8
[ 1313.286542] vhci_hcd: vhci_hub_control:635: port 1
[ 1313.286542] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1313.286542] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1313.286542] vhci_hcd: dump_port_status_diff:150:
[ 1313.286542] vhci_hcd: vhci_hub_control:643: bye
[ 1313.299454] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1313.310924] vhci_hcd: vhci_hub_control:431: GetPortStatus port 8
[ 1313.313818] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 100 0
[ 1313.313818] vhci_hcd: vhci_hub_control:635: port 7
[ 1313.313818] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000100 -> 00000100
[ 1313.313818] vhci_hcd: dump_port_status_diff:142: POWER
[ 1313.313818] vhci_hcd: dump_port_status_diff:150:
[ 1313.313818] vhci_hcd: vhci_hub_control:643: bye
[ 1313.333587] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1313.444543] usb usb1: vhci_bus_suspend
[ 1313.333587] vhci_hcd: vhci_hub_control:635: port 2
[ 1313.333587] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1313.333587] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1313.333587] vhci_hcd: dump_port_status_diff:150:
[ 1313.333587] vhci_hcd: vhci_hub_control:643: bye
[ 1313.451588] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1313.517930] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1313.521696] vhci_hcd: vhci_hub_control:635: port 3
[ 1313.521696] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1313.521696] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1313.521696] vhci_hcd: dump_port_status_diff:150:
[ 1313.521696] vhci_hcd: vhci_hub_control:643: bye
[ 1313.620525] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1313.635105] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1313.638723] vhci_hcd: vhci_hub_control:635: port 4
[ 1313.638723] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1313.638723] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1313.638723] vhci_hcd: dump_port_status_diff:150:
[ 1313.638723] vhci_hcd: vhci_hub_control:643: bye
[ 1313.723515] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1313.751733] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1313.753699] vhci_hcd: vhci_hub_control:635: port 5
[ 1313.753699] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1313.753699] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1313.753699] vhci_hcd: dump_port_status_diff:150:
[ 1313.753699] vhci_hcd: vhci_hub_control:643: bye
[ 1313.841500] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1313.876790] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1313.891050] vhci_hcd: vhci_hub_control:635: port 6
[ 1313.894553] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1313.913897] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1313.921834] vhci_hcd: dump_port_status_diff:150:
[ 1313.931720] vhci_hcd: vhci_hub_control:643: bye
[ 1313.945459] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1313.960661] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1313.965783] vhci_hcd: vhci_hub_control:635: port 7
[ 1313.965783] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000000 -> 00000200
[ 1313.965783] vhci_hcd: dump_port_status_diff:142: +POWER
[ 1313.965783] vhci_hcd: dump_port_status_diff:150:
[ 1313.965783] vhci_hcd: vhci_hub_control:643: bye
[ 1314.051268] _warn_unseeded_randomness: 1 callbacks suppressed
[ 1314.051359] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1314.092389] kobject: 'usb2-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1314.138891] kobject: 'usb2-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1314.161998] kobject: 'usb2-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1314.186280] kobject: 'usb2-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1314.207957] kobject: 'usb2-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1314.233528] kobject: 'usb2-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1314.256986] kobject: 'usb2-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1314.281453] kobject: 'usb2-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1314.319300] hub 2-0:1.0: USB hub found
[ 1314.328671] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2a00 wIndex 0
[ 1314.342380] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1314.350244] vhci_hcd: vhci_hub_control:635: port -1
[ 1314.350244] vhci_hcd: vhci_hub_control:643: bye
[ 1314.373840] hub 2-0:1.0: 8 ports detected
[ 1314.384085] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1314.398242] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1314.401601] vhci_hcd: vhci_hub_control:635: port -1
[ 1314.401601] vhci_hcd: vhci_hub_control:643: bye
[ 1314.460653] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1314.555727] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1314.615938] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1314.617574] vhci_hcd: vhci_hub_control:635: port 0
[ 1314.630304] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1314.647276] vhci_hcd: dump_port_status_diff:142: POWER
[ 1314.661858] vhci_hcd: dump_port_status_diff:150:
[ 1314.669830] vhci_hcd: vhci_hub_control:643: bye
[ 1314.684578] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1314.700407] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1314.702889] vhci_hcd: vhci_hub_control:635: port 1
[ 1314.702889] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1314.702889] vhci_hcd: dump_port_status_diff:142: POWER
[ 1314.713989] vhci_hcd: dump_port_status_diff:150:
[ 1314.713989] vhci_hcd: vhci_hub_control:643: bye
[ 1314.734569] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1314.748043] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1314.753906] vhci_hcd: vhci_hub_control:635: port 2
[ 1314.764307] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1314.765564] vhci_hcd: dump_port_status_diff:142: POWER
[ 1314.769557] vhci_hcd: dump_port_status_diff:150:
[ 1314.769557] vhci_hcd: vhci_hub_control:643: bye
[ 1314.784370] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1314.797399] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1314.815418] vhci_hcd: vhci_hub_control:635: port 3
[ 1314.822705] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1314.837849] vhci_hcd: dump_port_status_diff:142: POWER
[ 1314.849897] vhci_hcd: dump_port_status_diff:150:
[ 1314.857869] vhci_hcd: vhci_hub_control:643: bye
[ 1314.872677] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1314.886289] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1314.890030] vhci_hcd: vhci_hub_control:635: port 4
[ 1314.890030] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1314.890030] vhci_hcd: dump_port_status_diff:142: POWER
[ 1314.890030] vhci_hcd: dump_port_status_diff:150:
[ 1314.890030] vhci_hcd: vhci_hub_control:643: bye
[ 1314.943083] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1314.956240] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1314.959948] vhci_hcd: vhci_hub_control:635: port 5
[ 1314.959948] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1314.959948] vhci_hcd: dump_port_status_diff:142: POWER
[ 1314.959948] vhci_hcd: dump_port_status_diff:150:
[ 1314.997628] vhci_hcd: vhci_hub_control:643: bye
[ 1314.999996] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1315.002078] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1315.006043] vhci_hcd: vhci_hub_control:635: port 6
[ 1315.022019] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1315.033866] vhci_hcd: dump_port_status_diff:142: POWER
[ 1315.045854] vhci_hcd: dump_port_status_diff:150:
[ 1315.053863] vhci_hcd: vhci_hub_control:643: bye
[ 1315.068390] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1315.080933] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1315.084744] vhci_hcd: vhci_hub_control:635: port 7
[ 1315.084744] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1315.084744] vhci_hcd: dump_port_status_diff:142: POWER
[ 1315.084744] vhci_hcd: dump_port_status_diff:150:
[ 1315.084744] vhci_hcd: vhci_hub_control:643: bye
[ 1315.174841] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1315.191397] kobject: 'ep_81' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1315.216765] kobject: 'usb2-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1315.241340] kobject: 'usb2-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1315.264327] kobject: 'usb2-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1315.307571] kobject: 'usb2-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1315.329340] kobject: 'usb2-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1315.357085] kobject: 'usb2-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1315.380355] kobject: 'usb2-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1315.402264] kobject: 'usb2-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1315.433714] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1315.476371] hub 2-0:1.0: USB hub found
[ 1315.486095] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2a00 wIndex 0
[ 1315.499349] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1315.503118] vhci_hcd: vhci_hub_control:635: port -1
[ 1315.516459] vhci_hcd: vhci_hub_control:643: bye
[ 1315.530065] hub 2-0:1.0: 8 ports detected
[ 1315.549941] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1315.562964] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1315.566774] vhci_hcd: vhci_hub_control:635: port -1
[ 1315.590273] vhci_hcd: vhci_hub_control:643: bye
[ 1315.683394] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1315.696455] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1315.706850] vhci_hcd: vhci_hub_control:635: port 0
[ 1315.713751] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1315.729753] vhci_hcd: dump_port_status_diff:142: POWER
[ 1315.741909] vhci_hcd: dump_port_status_diff:150:
[ 1315.750921] vhci_hcd: vhci_hub_control:643: bye
[ 1315.766899] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1315.809900] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1315.813577] vhci_hcd: vhci_hub_control:635: port 1
[ 1315.813577] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1315.813577] vhci_hcd: dump_port_status_diff:142: POWER
[ 1315.813577] vhci_hcd: dump_port_status_diff:150:
[ 1315.813577] vhci_hcd: vhci_hub_control:643: bye
[ 1315.884369] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1315.897433] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1315.910712] vhci_hcd: vhci_hub_control:635: port 2
[ 1315.911098] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1315.934462] vhci_hcd: dump_port_status_diff:142: POWER
[ 1315.941821] vhci_hcd: dump_port_status_diff:150:
[ 1315.949805] vhci_hcd: vhci_hub_control:643: bye
[ 1315.967420] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1315.980386] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1315.986542] vhci_hcd: vhci_hub_control:635: port 3
[ 1315.986542] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1315.986542] vhci_hcd: dump_port_status_diff:142: POWER
[ 1315.986542] vhci_hcd: dump_port_status_diff:150:
[ 1315.986542] vhci_hcd: vhci_hub_control:643: bye
[ 1316.056051] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1316.068721] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1316.072536] vhci_hcd: vhci_hub_control:635: port 4
[ 1316.072536] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1316.072536] vhci_hcd: dump_port_status_diff:142: POWER
[ 1316.072536] vhci_hcd: dump_port_status_diff:150:
[ 1316.118211] vhci_hcd: vhci_hub_control:643: bye
[ 1316.146761] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1316.160468] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1316.161736] vhci_hcd: vhci_hub_control:635: port 5
[ 1316.161736] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1316.161736] vhci_hcd: dump_port_status_diff:142: POWER
[ 1316.161736] vhci_hcd: dump_port_status_diff:150:
[ 1316.161736] vhci_hcd: vhci_hub_control:643: bye
[ 1316.227807] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1316.240352] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1316.244097] vhci_hcd: vhci_hub_control:635: port 6
[ 1316.258136] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1316.274094] vhci_hcd: dump_port_status_diff:142: POWER
[ 1316.285767] vhci_hcd: dump_port_status_diff:150:
[ 1316.297847] vhci_hcd: vhci_hub_control:643: bye
[ 1316.313500] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1316.326361] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1316.336407] vhci_hcd: vhci_hub_control:635: port 7
[ 1316.336407] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1316.336407] vhci_hcd: dump_port_status_diff:142: POWER
[ 1316.336407] vhci_hcd: dump_port_status_diff:150:
[ 1316.336407] vhci_hcd: vhci_hub_control:643: bye
[ 1316.403074] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1316.418274] kobject: 'usb2-port8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1316.439403] kobject: 'usb2-port7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1316.460858] kobject: 'usb2-port6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1316.482380] kobject: 'usb2-port5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1316.505275] kobject: 'usb2-port4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1316.525012] kobject: 'usb2-port3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1316.548904] kobject: 'usb2-port2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1316.554802] kobject: 'usb2-port1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1316.558779] hub 2-0:1.0: USB hub found
[ 1316.562981] vhci_hcd: vhci_hub_control:336: typeReq a006 wValue 2a00 wIndex 0
[ 1316.586503] vhci_hcd: vhci_hub_control:404: GetHubDescriptor
[ 1316.589662] vhci_hcd: vhci_hub_control:635: port -1
[ 1316.589662] vhci_hcd: vhci_hub_control:643: bye
[ 1316.616995] hub 2-0:1.0: 8 ports detected
[ 1316.628764] vhci_hcd: vhci_hub_control:336: typeReq a000 wValue 0 wIndex 0
[ 1316.643089] vhci_hcd: vhci_hub_control:427: GetHubStatus
[ 1316.645758] vhci_hcd: vhci_hub_control:635: port -1
[ 1316.645758] vhci_hcd: vhci_hub_control:643: bye
[ 1316.675136] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1316.761044] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 1
[ 1316.774854] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1316.777975] vhci_hcd: vhci_hub_control:635: port 0
[ 1316.777975] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1316.777975] vhci_hcd: dump_port_status_diff:142: POWER
[ 1316.777975] vhci_hcd: dump_port_status_diff:150:
[ 1316.777975] vhci_hcd: vhci_hub_control:643: bye
[ 1316.843352] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 2
[ 1316.856314] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1316.864869] vhci_hcd: vhci_hub_control:635: port 1
[ 1316.864869] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1316.864869] vhci_hcd: dump_port_status_diff:142: POWER
[ 1316.864869] vhci_hcd: dump_port_status_diff:150:
[ 1316.864869] vhci_hcd: vhci_hub_control:643: bye
[ 1316.924310] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 3
[ 1316.938033] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1316.941678] vhci_hcd: vhci_hub_control:635: port 2
[ 1316.941678] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1316.941678] vhci_hcd: dump_port_status_diff:142: POWER
[ 1316.941678] vhci_hcd: dump_port_status_diff:150:
[ 1316.941678] vhci_hcd: vhci_hub_control:643: bye
[ 1316.981340] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 4
[ 1317.010053] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1317.013911] vhci_hcd: vhci_hub_control:635: port 3
[ 1317.013911] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1317.013911] vhci_hcd: dump_port_status_diff:142: POWER
[ 1317.013911] vhci_hcd: dump_port_status_diff:150:
[ 1317.013911] vhci_hcd: vhci_hub_control:643: bye
[ 1317.055875] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 5
[ 1317.071683] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1317.082086] vhci_hcd: vhci_hub_control:635: port 4
[ 1317.089897] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1317.105906] vhci_hcd: dump_port_status_diff:142: POWER
[ 1317.113848] vhci_hcd: dump_port_status_diff:150:
[ 1317.117583] vhci_hcd: vhci_hub_control:643: bye
[ 1317.121819] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 6
[ 1317.124092] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1317.125523] vhci_hcd: vhci_hub_control:635: port 5
[ 1317.125523] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1317.125523] vhci_hcd: dump_port_status_diff:142: POWER
[ 1317.125523] vhci_hcd: dump_port_status_diff:150:
[ 1317.125523] vhci_hcd: vhci_hub_control:643: bye
[ 1317.174976] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 7
[ 1317.193969] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1317.197643] vhci_hcd: vhci_hub_control:635: port 6
[ 1317.197643] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1317.197643] vhci_hcd: dump_port_status_diff:142: POWER
[ 1317.197643] vhci_hcd: dump_port_status_diff:150:
[ 1317.197643] vhci_hcd: vhci_hub_control:643: bye
[ 1317.258723] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 8 wIndex 8
[ 1317.270999] vhci_hcd: vhci_hub_control:541: SetPortFeature: USB_PORT_FEAT_POWER
[ 1317.282246] vhci_hcd: vhci_hub_control:635: port 7
[ 1317.289862] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1317.301821] vhci_hcd: dump_port_status_diff:142: POWER
[ 1317.313841] vhci_hcd: dump_port_status_diff:150:
[ 1317.321845] vhci_hcd: vhci_hub_control:643: bye
[ 1317.344091] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1317.365877] vhci_hcd: vhci_hcd_probe:1360: bye
[ 1317.370464] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[ 1317.373604] kobject: '2-0:1.0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1317.390381] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 1317.409238] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 1317.440313] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 1
[ 1317.465853] vhci_hcd: vhci_hub_control:431: GetPortStatus port 1
[ 1317.481823] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 200 0
[ 1317.497783] vhci_hcd: vhci_hub_control:635: port 0
[ 1317.513849] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1317.537856] vhci_hcd: dump_port_status_diff:142: POWER
[ 1317.553860] vhci_hcd: dump_port_status_diff:150:
[ 1317.565852] vhci_hcd: vhci_hub_control:643: bye
[ 1317.591746] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 2
[ 1317.595568] evbug: Connected device: input1 (Power Button at LNXPWRBN/button/input0)
[ 1317.615636] vhci_hcd: vhci_hub_control:431: GetPortStatus port 2
[ 1317.615698] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 200 0
[ 1317.615760] vhci_hcd: vhci_hub_control:635: port 1
[ 1317.668480] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1317.693247] usbcore: registered new interface driver appletouch
[ 1317.668480] vhci_hcd: dump_port_status_diff:142: POWER
[ 1317.668480] vhci_hcd: dump_port_status_diff:150:
[ 1317.668480] vhci_hcd: vhci_hub_control:643: bye
[ 1317.710942] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 3
[ 1317.718750] usbcore: registered new interface driver synaptics_usb
[ 1317.729372] vhci_hcd: vhci_hub_control:431: GetPortStatus port 3
[ 1317.766639] i2c /dev entries driver
[ 1317.753969] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 200 0
[ 1317.753969] vhci_hcd: vhci_hub_control:635: port 2
[ 1317.753969] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1317.753969] vhci_hcd: dump_port_status_diff:142: POWER
[ 1317.753969] vhci_hcd: dump_port_status_diff:150:
[ 1317.753969] vhci_hcd: vhci_hub_control:643: bye
[ 1317.775133] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 4
[ 1317.812714] usbcore: registered new interface driver i2c-diolan-u2c
[ 1317.814957] vhci_hcd: vhci_hub_control:431: GetPortStatus port 4
[ 1317.857023] i2c-parport-light: adapter type unspecified
[ 1317.817923] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 200 0
[ 1317.817923] vhci_hcd: vhci_hub_control:635: port 3
[ 1317.817923] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1317.817923] vhci_hcd: dump_port_status_diff:142: POWER
[ 1317.817923] vhci_hcd: dump_port_status_diff:150:
[ 1317.817923] vhci_hcd: vhci_hub_control:643: bye
[ 1317.871255] usbcore: registered new interface driver i2c-tiny-usb
[ 1317.882332] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 5
[ 1317.899082] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2
[ 1317.901451] vhci_hcd: vhci_hub_control:431: GetPortStatus port 5
[ 1317.923460] evbug: Connected device: input2 (AT Translated Set 2 keyboard at isa0060/serio0/input0)
[ 1317.913275] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 200 0
[ 1317.913275] vhci_hcd: vhci_hub_control:635: port 4
[ 1317.913275] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1317.913275] vhci_hcd: dump_port_status_diff:142: POWER
[ 1317.913275] vhci_hcd: dump_port_status_diff:150:
[ 1317.913275] vhci_hcd: vhci_hub_control:643: bye
[ 1317.954367] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 6
[ 1318.083086] IR NEC protocol handler initialized
[ 1318.095920] vhci_hcd: vhci_hub_control:431: GetPortStatus port 6
[ 1318.111087] _warn_unseeded_randomness: 1 callbacks suppressed
[ 1318.111247] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1318.104444] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 200 0
[ 1318.104444] vhci_hcd: vhci_hub_control:635: port 5
[ 1318.104444] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1318.104444] vhci_hcd: dump_port_status_diff:142: POWER
[ 1318.104444] vhci_hcd: dump_port_status_diff:150:
[ 1318.104444] vhci_hcd: vhci_hub_control:643: bye
[ 1318.122012] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 7
[ 1318.145826] IR RC5(x/sz) protocol handler initialized
[ 1318.156970] vhci_hcd: vhci_hub_control:431: GetPortStatus port 7
[ 1318.157012] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 200 0
[ 1318.171682] kobject: '(null)' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1318.171465] vhci_hcd: vhci_hub_control:635: port 6
[ 1318.171465] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1318.171465] vhci_hcd: dump_port_status_diff:142: POWER
[ 1318.171465] vhci_hcd: dump_port_status_diff:150:
[ 1318.171465] vhci_hcd: vhci_hub_control:643: bye
[ 1318.179853] vhci_hcd: vhci_hub_control:336: typeReq a300 wValue 0 wIndex 8
[ 1318.191680] IR JVC protocol handler initialized
[ 1318.202215] vhci_hcd: vhci_hub_control:431: GetPortStatus port 8
[ 1318.206644] evbug: Disconnected device: input2
[ 1318.205636] vhci_hcd: vhci_hub_control:487: GetPortStatus bye 200 0
[ 1318.205636] vhci_hcd: vhci_hub_control:635: port 7
[ 1318.213650] IR Sony protocol handler initialized
[ 1318.205636] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 00000200
[ 1318.205636] vhci_hcd: dump_port_status_diff:142: POWER
[ 1318.205636] vhci_hcd: dump_port_status_diff:150:
[ 1318.205636] vhci_hcd: vhci_hub_control:643: bye
[ 1318.225210] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 1b wIndex 1
[ 1318.236588] kobject: 'input' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1318.243426] vhci_hcd: vhci_hub_control:591: SetPortFeature: default 27
[ 1318.243464] vhci_hcd: vhci_hub_control:635: port 0
[ 1318.243524] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 08000200
[ 1318.243561] vhci_hcd: dump_port_status_diff:142: POWER
[ 1318.243619] vhci_hcd: dump_port_status_diff:142: +R27
[ 1318.243654] vhci_hcd: dump_port_status_diff:150:
[ 1318.243693] vhci_hcd: vhci_hub_control:643: bye
[ 1318.254906] IR SANYO protocol handler initialized
[ 1318.262076] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 1b wIndex 2
[ 1318.266246] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1318.267782] vhci_hcd: vhci_hub_control:591: SetPortFeature: default 27
[ 1318.269364] IR XMP protocol handler initialized
[ 1318.271713] vhci_hcd: vhci_hub_control:635: port 1
[ 1318.271713] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 08000200
[ 1318.271713] vhci_hcd: dump_port_status_diff:142: POWER
[ 1318.271713] vhci_hcd: dump_port_status_diff:142: +R27
[ 1318.271713] vhci_hcd: dump_port_status_diff:150:
[ 1318.271713] vhci_hcd: vhci_hub_control:643: bye
[ 1318.278969] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 1b wIndex 3
[ 1318.291083] ir_imon_decoder: IR iMON protocol handler initialized
[ 1318.300773] vhci_hcd: vhci_hub_control:591: SetPortFeature: default 27
[ 1318.308812] IR RCMM protocol handler initialized
[ 1318.301929] vhci_hcd: vhci_hub_control:635: port 2
[ 1318.301929] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 08000200
[ 1318.301929] vhci_hcd: dump_port_status_diff:142: POWER
[ 1318.301929] vhci_hcd: dump_port_status_diff:142: +R27
[ 1318.301929] vhci_hcd: dump_port_status_diff:150:
[ 1318.301929] vhci_hcd: vhci_hub_control:643: bye
[ 1318.323240] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 1b wIndex 4
[ 1318.335253] Driver for 1-wire Dallas network protocol.
[ 1318.342693] vhci_hcd: vhci_hub_control:591: SetPortFeature: default 27
[ 1318.354826] usbcore: registered new interface driver DS9490R
[ 1318.350359] vhci_hcd: vhci_hub_control:635: port 3
[ 1318.350359] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 08000200
[ 1318.350359] vhci_hcd: dump_port_status_diff:142: POWER
[ 1318.350359] vhci_hcd: dump_port_status_diff:142: +R27
[ 1318.350359] vhci_hcd: dump_port_status_diff:150:
[ 1318.350359] vhci_hcd: vhci_hub_control:643: bye
[ 1318.363318] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 1b wIndex 5
[ 1318.373918] w1_f0d_init()
[ 1318.380854] vhci_hcd: vhci_hub_control:591: SetPortFeature: default 27
[ 1318.456872] applesmc: supported laptop not found!
[ 1318.426848] vhci_hcd: vhci_hub_control:635: port 4
[ 1318.426848] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 08000200
[ 1318.426848] vhci_hcd: dump_port_status_diff:142: POWER
[ 1318.426848] vhci_hcd: dump_port_status_diff:142: +R27
[ 1318.426848] vhci_hcd: dump_port_status_diff:150:
[ 1318.470134] applesmc: driver init failed (ret=-19)!
[ 1318.426848] vhci_hcd: vhci_hub_control:643: bye
[ 1318.508020] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 1b wIndex 6
[ 1318.529434] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3
[ 1318.530990] vhci_hcd: vhci_hub_control:591: SetPortFeature: default 27
[ 1318.556050] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1318.541331] vhci_hcd: vhci_hub_control:635: port 5
[ 1318.541331] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 08000200
[ 1318.541331] vhci_hcd: dump_port_status_diff:142: POWER
[ 1318.541331] vhci_hcd: dump_port_status_diff:142: +R27
[ 1318.541331] vhci_hcd: dump_port_status_diff:150:
[ 1318.541331] vhci_hcd: vhci_hub_control:643: bye
[ 1318.570563] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 1b wIndex 7
[ 1318.587584] evbug: Connected device: input3 (AT Translated Set 2 keyboard at isa0060/serio0/input0)
[ 1318.601279] vhci_hcd: vhci_hub_control:591: SetPortFeature: default 27
[ 1318.610859] kobject: 'it87' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1318.604725] vhci_hcd: vhci_hub_control:635: port 6
[ 1318.604725] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 08000200
[ 1318.604725] vhci_hcd: dump_port_status_diff:142: POWER
[ 1318.604725] vhci_hcd: dump_port_status_diff:142: +R27
[ 1318.604725] vhci_hcd: dump_port_status_diff:150:
[ 1318.604725] vhci_hcd: vhci_hub_control:643: bye
[ 1318.623248] vhci_hcd: vhci_hub_control:336: typeReq 2303 wValue 1b wIndex 8
[ 1318.647730] kobject: 'input4' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1318.650080] vhci_hcd: vhci_hub_control:591: SetPortFeature: default 27
[ 1318.689763] kobject: 'mc13783-adc' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1318.681789] vhci_hcd: vhci_hub_control:635: port 7
[ 1318.681789] vhci_hcd: dump_port_status_diff:128: status prev -> new: 00000200 -> 08000200
[ 1318.681789] vhci_hcd: dump_port_status_diff:142: POWER
[ 1318.681789] vhci_hcd: dump_port_status_diff:142: +R27
[ 1318.681789] vhci_hcd: dump_port_status_diff:150:
[ 1318.681789] vhci_hcd: vhci_hub_control:643: bye
[ 1318.727982] usb usb2: vhci_bus_suspend
[ 1318.771526] kobject: 'nct6683' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1319.292240] kobject: 'nct6775' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1319.330901] _warn_unseeded_randomness: 6 callbacks suppressed
[ 1319.330968] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1319.438889] usbcore: registered new interface driver pcwd_usb
[ 1319.454502] wafer5823wdt: WDT driver for Wafer 5823 single board computer initialising
[ 1319.476856] wafer5823wdt: initialized. timeout=60 sec (nowayout=0)
[ 1319.513867] it87_wdt: no device
[ 1319.515107] sc1200wdt: build 20020303
[ 1319.516390] sc1200wdt: io parameter must be specified
[ 1319.525301] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1319.540024] kobject: 'scl200wdt' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1319.579978] sbc60xxwdt: I/O address 0x0443 already in use
[ 1319.590469] cpu5wdt: misc_register failed
[ 1319.598736] smsc37b787_wdt: SMsC 37B787 watchdog component driver 1.1 initialising...
[ 1319.616113] smsc37b787_wdt: Unable to register miscdev on minor 130
[ 1319.632341] w83977f_wdt: driver v1.00
[ 1319.639762] w83977f_wdt: cannot register miscdev on minor=130 (err=-16)
[ 1319.652337] sbc_epx_c3: cannot register miscdev on minor=130 (err=-16)
[ 1319.679523] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1319.716274] md-cluster: support raid1 and raid10 (limited support)
[ 1319.733775] Registering Cluster MD functions
[ 1319.766865] usbcore: registered new interface driver bpa10x
[ 1319.781829] intel_pstate: CPU model not supported
[ 1319.808559] kobject: 'mc13xxx-led' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1319.928912] kobject: '32' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1319.949855] kobject: '33' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1319.961882] kobject: '34' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1319.965522] kobject: '35' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1319.968282] kobject: '36' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1319.976696] kobject: '37' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1319.994066] kobject: '38' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1320.025858] kobject: '39' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1320.044420] kobject: '40' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1320.061922] kobject: '41' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1320.080068] kobject: '42' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1320.098278] kobject: '43' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1320.131105] kobject: 'acpi' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1320.145112] kobject: 'smbios' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1320.159654] kobject: 'tpm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1320.175701] kobject: 'etc' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1320.207130] kobject: 'genroms' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1320.225276] kobject: 'by_name' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1320.280532] fw_cfg: probe of QEMU0002:00 failed with error -16
[ 1320.297743] hidraw: raw HID events driver (C) Jiri Kosina
[ 1320.346637] _warn_unseeded_randomness: 5 callbacks suppressed
[ 1320.346705] random: get_random_u64 called from copy_process+0x5b5/0x4642 with crng_init=0
[ 1320.488874] usbcore: registered new interface driver usbhid
[ 1320.507765] usbhid: USB HID core driver
[ 1320.537062] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1320.568432] kobject: 'chromeos_pstore' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1320.590071] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1320.633110] fake-fmc-carrier: mezzanine 0
[ 1320.643412] Manufacturer: fake-vendor
[ 1320.652034] Product name: fake-design-for-testing
[ 1320.671022] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[ 1320.685899] fmc_write_eeprom fake-design-for-testing-f001: fmc_write_eeprom: no busid passed, refusing all cards
[ 1320.718878] gnss: GNSS driver registered with major 236
[ 1320.751497] drop_monitor: Initializing network drop monitor service
[ 1320.771723] NET: Registered protocol family 26
[ 1320.799043] ipip: IPv4 and MPLS over IPv4 tunneling driver
[ 1320.815108] NET: Registered protocol family 10
[ 1320.898043] Segment Routing with IPv6
[ 1320.909034] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[ 1320.999673] bpfilter: Loaded bpfilter_umh pid 206
Started bpfilter
[ 1321.040834] NET: Registered protocol family 5
[ 1321.060437] NET: Unregistered protocol family 5
[ 1321.067242] NET: Registered protocol family 9
[ 1321.078488] X25: Linux Version 0.2
[ 1321.084120] can: controller area network core (rev 20170425 abi 9)
[ 1321.095211] NET: Registered protocol family 29
[ 1321.096724] can: netlink gateway (rev 20170425) max_hops=1
[ 1321.099028] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 1321.100857] Bluetooth: BNEP filters: multicast
[ 1321.102044] Bluetooth: BNEP socket layer initialized
[ 1321.122350] NET: Registered protocol family 33
[ 1321.132233] Key type rxrpc registered
[ 1321.139814] Key type rxrpc_s registered
[ 1321.153030] NET: Registered protocol family 35
[ 1321.165055] 8021q: 802.1Q VLAN Support v1.8
[ 1321.177725] sctp: Hash tables configured (bind 32/51)
[ 1321.206957] lib80211: common routines for IEEE802.11 drivers
[ 1321.218327] lib80211_crypt: registered algorithm 'NULL'
[ 1321.228478] lib80211_crypt: registered algorithm 'WEP'
[ 1321.245969] lib80211_crypt: registered algorithm 'CCMP'
[ 1321.287652] lib80211_crypt: registered algorithm 'TKIP'
[ 1321.297916] tipc: Activated (version 2.0.0)
[ 1321.315059] NET: Registered protocol family 30
[ 1321.334351] kobject: 'event1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1321.336612] tipc: Started in single node mode
[ 1321.363319] 9pnet: Installing 9P2000 support
[ 1321.375303] NET: Registered protocol family 37
[ 1321.387622] Key type ceph registered
[ 1321.407096] _warn_unseeded_randomness: 32 callbacks suppressed
[ 1321.407263] random: get_random_u64 called from copy_process+0x5b5/0x4642 with crng_init=0
[ 1321.474417] random: get_random_u64 called from cache_random_seq_create+0x54/0xe9 with crng_init=0
[ 1321.479742] libceph: loaded (mon/osd proto 15/24)
[ 1321.484688] ... APIC ID: 00000000 (0)
[ 1321.485490] ... APIC VERSION: 00050014
[ 1321.485490] 0000000000000000000000000000000000000000000000000000000000000000
[ 1321.485490] 0000000000000000000000000000000000000000000000000000000000001000
[ 1321.533792] number of MP IRQ sources: 15.
[ 1321.542215] number of IO-APIC #0 registers: 24.
[ 1321.551101] testing the IO APIC.......................
[ 1321.561926] IO APIC #0......
[ 1321.573781] .... register #00: 00000000
[ 1321.581262] ....... : physical APIC id: 00
[ 1321.590009] ....... : Delivery Type: 0
[ 1321.591982] ....... : LTS : 0
[ 1321.593750] .... register #01: 00170011
[ 1321.595204] ....... : max redirection entries: 17
[ 1321.596841] ....... : PRQ implemented: 0
[ 1321.598188] ....... : IO APIC version: 11
[ 1321.599405] .... register #02: 00000000
[ 1321.604634] ....... : arbitration: 00
[ 1321.612470] .... IRQ redirection table:
[ 1321.619891] IOAPIC 0:
[ 1321.625177] pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.636559] pin01, enabled , edge , high, V(22), IRR(0), S(0), logical , D(02), M(0)
[ 1321.640269] pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , D(01), M(0)
[ 1321.642922] pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.645132] pin04, enabled , edge , high, V(22), IRR(0), S(0), logical , D(01), M(0)
[ 1321.658495] pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.674655] pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.683626] pin07, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.687082] pin08, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.689648] pin09, enabled , level, high, V(21), IRR(0), S(0), logical , D(02), M(0)
[ 1321.691836] pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.703543] pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.718776] pin0c, enabled , edge , high, V(21), IRR(0), S(0), logical , D(01), M(0)
[ 1321.742077] pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.756820] pin0e, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.772040] pin0f, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.810086] pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.827531] pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.843562] pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.858606] pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.867521] pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.870911] pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.873707] pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.887868] pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 1321.904274] IRQ to pin mappings:
[ 1321.911329] IRQ0 -> 0:2
[ 1321.916451] IRQ1 -> 0:1
[ 1321.921963] IRQ3 -> 0:3
[ 1321.927800] IRQ4 -> 0:4
[ 1321.932945] IRQ5 -> 0:5
[ 1321.950672] IRQ6 -> 0:6
[ 1321.955991] IRQ7 -> 0:7
[ 1321.961277] IRQ8 -> 0:8
[ 1321.968073] IRQ9 -> 0:9
[ 1321.973393] IRQ10 -> 0:10
[ 1321.979386] IRQ11 -> 0:11
[ 1321.985053] IRQ12 -> 0:12
[ 1321.993835] IRQ13 -> 0:13
[ 1321.999998] IRQ14 -> 0:14
[ 1322.010494] IRQ15 -> 0:15
[ 1322.016461] .................................... done.
[ 1322.029552] random: get_random_bytes called from init_oops_id+0x22/0x31 with crng_init=0
[ 1322.046046] sched_clock: Marking stable (1318591984963, 3453577371)->(1323905114948, -1859552614)
[ 1322.154024] PM: Magic number: 7:921:488
[ 1322.163812] regulator regulator.0: hash matches
[ 1322.205122] Duplicate name in testcase-data, renamed to "duplicate-name#1"
[ 1322.424808] ### dt-test ### start of unittest - you will see error messages
[ 1322.452056] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1
[ 1322.477820] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1
[ 1322.502551] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle
[ 1322.516350] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle
[ 1322.527402] OF: /testcase-data/phandle-tests/consumer-a: #phandle-cells = 3 found 0
[ 1322.530709] OF: /testcase-data/phandle-tests/consumer-a: #phandle-cells = 3 found 0
[ 1322.535260] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1
[ 1322.588717] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle
[ 1322.604344] OF: /testcase-data/phandle-tests/consumer-b: #phandle-cells = 2 found 0
[ 1322.761027] _warn_unseeded_randomness: 2 callbacks suppressed
[ 1322.761118] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1322.789125] kobject: 'testcase-data:platform-tests:test-device@0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1322.819869] kobject: 'testcase-data:platform-tests:test-device@1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1322.849077] kobject: 'unittest-bus.0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1322.887087] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1322.931724] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status
[ 1322.983877] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status
[ 1323.016411] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1323.032576] kobject: 'testcase-data:overlay-node:test-bus:test-unittest1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1323.064245] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status
[ 1323.123310] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status
[ 1323.157045] kobject: 'testcase-data:overlay-node:test-bus:test-unittest3' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1323.211250] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status
[ 1323.262667] kobject: 'testcase-data:overlay-node:test-bus:test-unittest5' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1323.295691] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status
[ 1323.321686] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status
[ 1323.382458] ==================================================================
[ 1323.396303] BUG: KASAN: use-after-free in kobject_uevent_env+0x1b6/0xb19
[ 1323.409096] Read of size 8 at addr ffff888015bea418 by task kworker/0:1/12
[ 1323.422359]
[ 1323.425722] CPU: 0 PID: 12 Comm: kworker/0:1 Not tainted 5.2.0-rc1-00387-g47cdee29 #1
[ 1323.440730] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 1323.456710] Workqueue: events kobject_delayed_cleanup
[ 1323.466558] Call Trace:
[ 1323.471586] dump_stack+0x15a/0x215
[ 1323.478551] ? kobject_uevent_env+0x1b6/0xb19
[ 1323.487268] print_address_description+0x67/0x1f5
[ 1323.496245] ? kobject_uevent_env+0x1b6/0xb19
[ 1323.504760] ? kobject_uevent_env+0x1b6/0xb19
[ 1323.513348] __kasan_report+0x183/0x1b3
[ 1323.517658] ? kobject_uevent_env+0x1b6/0xb19
[ 1323.519841] kobject_uevent_env+0x1b6/0xb19
[ 1323.521639] ? lock_acquire+0x1c3/0x2d3
[ 1323.523030] ? process_one_work+0x6ac/0xd23
[ 1323.524433] kobject_delayed_cleanup+0x199/0x2e1
[ 1323.527592] process_one_work+0x79d/0xd23
[ 1323.535571] ? pwq_dec_nr_in_flight+0x23a/0x23a
[ 1323.544512] worker_thread+0x5ee/0x7ce
[ 1323.551903] kthread+0x33f/0x34e
[ 1323.558288] ? process_scheduled_works+0x6d/0x6d
[ 1323.567296] ? __kthread_create_on_node+0x346/0x346
[ 1323.576889] ret_from_fork+0x24/0x30
[ 1323.583919]
[ 1323.587061] Allocated by task 1:
[ 1323.590922] __kasan_kmalloc+0x38/0x91
[ 1323.593098] kmem_cache_alloc_trace+0x1b8/0x260
[ 1323.594999] fw_cfg_register_dir_entries+0x3ec/0x58b
[ 1323.596779] fw_cfg_sysfs_probe+0x389/0x3f8
[ 1323.598183] platform_drv_probe+0xb6/0x127
[ 1323.601994] really_probe+0x366/0xa6a
[ 1323.608955] driver_probe_device+0x255/0x2ae
[ 1323.617317] device_driver_attach+0xe1/0x11c
[ 1323.625328] __driver_attach+0x212/0x21c
[ 1323.632882] bus_for_each_dev+0x100/0x13f
[ 1323.640363] bus_add_driver+0x2f8/0x48f
[ 1323.647682] driver_register+0x263/0x2d7
[ 1323.655305] fw_cfg_sysfs_init+0x5c/0x7e
[ 1323.662758] do_one_initcall+0x101/0x394
[ 1323.670529] kernel_init_freeable+0x3e3/0x549
[ 1323.678952] kernel_init+0xc/0x108
[ 1323.685541] ret_from_fork+0x24/0x30
[ 1323.692639]
[ 1323.695749] Freed by task 12:
[ 1323.700022] __kasan_slab_free+0x120/0x191
[ 1323.702320] kfree+0x129/0x1a1
[ 1323.703737] kobject_delayed_cleanup+0x2a6/0x2e1
[ 1323.705530] process_one_work+0x79d/0xd23
[ 1323.706927] worker_thread+0x5ee/0x7ce
[ 1323.708135] kthread+0x33f/0x34e
[ 1323.709115] ret_from_fork+0x24/0x30
[ 1323.713212]
[ 1323.716446] The buggy address belongs to the object at ffff888015bea2c0
[ 1323.716446] which belongs to the cache kmalloc-512 of size 512
[ 1323.740817] The buggy address is located 344 bytes inside of
[ 1323.740817] 512-byte region [ffff888015bea2c0, ffff888015bea4c0)
[ 1323.763056] The buggy address belongs to the page:
[ 1323.772353] page:ffffea00004c1b30 refcount:1 mapcount:0 mapping:ffff888000098400 index:0x0
[ 1323.787970] flags: 0xfffc0000000200(slab)
[ 1323.795913] raw: 00fffc0000000200 ffffea0000444740 ffffea00004c17b8 ffff888000098400
[ 1323.810821] raw: 0000000000000000 ffff888015bea040 0000000100000006
[ 1323.820507] page dumped because: kasan: bad access detected
[ 1323.823051]
[ 1323.823744] Memory state around the buggy address:
[ 1323.825548] ffff888015bea300: fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb
[ 1323.827869] ffff888015bea380: fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb
[ 1323.830471] >ffff888015bea400: fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb
[ 1323.844431] ^
[ 1323.852436] ffff888015bea480: fb fb fb fb fb fb fb fb fc fc fc fc fc fc fc fc
[ 1323.866540] ffff888015bea500: fc fc fc fc fc fc fc fc fb fb fb fb fb fb fb fb
[ 1323.880570] ==================================================================
[ 1323.890228] Disabling lock debugging due to kernel taint
[ 1323.898856] _warn_unseeded_randomness: 5 callbacks suppressed
[ 1323.898918] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1323.927949] kobject: 'testcase-data:overlay-node:test-bus:test-unittest7' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1323.949021] kobject: 'testcase-data:overlay-node:test-bus:test-unittest6' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1323.953835] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1323.958811] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status
[ 1324.002594] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo
[ 1324.027126] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8
[ 1324.053640] OF: overlay: overlay #6 is not topmost
[ 1324.068415] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1324.078431] kobject: 'testcase-data:overlay-node:test-bus:test-unittest8' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1324.150336] kobject: 'by_key' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1324.222796] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11:test-unittest111' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1324.255392] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1324.282127] OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /testcase-data/overlay-node/test-bus/test-unittest11/test-unittest111
[ 1324.320526] OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /testcase-data/overlay-node/test-bus/test-unittest11
[ 1324.423219] kobject: '0-0009' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1324.447267] kobject: '0-000a' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1324.466361] kobject: '(null)' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1324.485318] ------------[ cut here ]------------
[ 1324.494253] ODEBUG: free active (active state 0) object type: timer_list hint: delayed_work_timer_fn+0x0/0x6b
[ 1324.513020] WARNING: CPU: 0 PID: 1 at lib/debugobjects.c:328 debug_print_object+0x169/0x181
[ 1324.532042] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G B 5.2.0-rc1-00387-g47cdee29 #1
[ 1324.548347] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 1324.564033] RIP: 0010:debug_print_object+0x169/0x181
[ 1324.573655] Code: 3c 02 00 74 05 e8 f1 39 48 ff 48 8b 14 dd 60 68 2f 84 4d 89 e9 49 89 e8 44 89 f1 4c 89 e6 48 c7 c7 c0 5a 2f 84 e8 ce f9 12 ff <0f> 0b 5b ff 05 bb f7 9b 03 5d 41 5c 41 5d 41 5e c3 ff 05 ad f7 9b
[ 1324.608552] RSP: 0000:ffff88800ff676d0 EFLAGS: 00010082
[ 1324.618466] RAX: 0000000000000000 RBX: 0000000000000003 RCX: 0000000000000000
[ 1324.632020] RDX: 1ffffffff0dde404 RSI: 0000000000000004 RDI: ffffed1001fececf
[ 1324.645369] RBP: ffffffff8408fb00 R08: fffffbfff09eac51 R09: fffffbfff09eac50
[ 1324.658850] R10: ffffed1001fececf R11: 0000000000000000 R12: ffffffff842f5e40
[ 1324.672591] R13: ffffffff8118ec80 R14: 0000000000000000 R15: 0000000000000000
[ 1324.686031] FS: 0000000000000000(0000) GS:ffff888019800000(0000) knlGS:0000000000000000
[ 1324.701597] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1324.712639] CR2: 00007ffc4dc4ef88 CR3: 000000001430a003 CR4: 00000000000206b0
[ 1324.722429] Call Trace:
[ 1324.723670] __debug_check_no_obj_freed+0x165/0x30d
[ 1324.725792] kfree+0x116/0x1a1
[ 1324.726945] i2cdev_detach_adapter+0x8f/0xf3
[ 1324.728396] notifier_call_chain+0xae/0xf4
[ 1324.729700] __blocking_notifier_call_chain+0x78/0x90
[ 1324.732973] device_del+0x178/0x7f5
[ 1324.739909] ? __pm_runtime_disable+0x22e/0x256
[ 1324.748884] ? klist_children_put+0x39/0x39
[ 1324.757045] ? lockdep_init_map+0x216/0x5af
[ 1324.765317] ? lockdep_init_map+0x216/0x5af
[ 1324.773089] device_unregister+0x83/0x8c
[ 1324.775258] i2c_del_adapter+0x489/0x501
[ 1324.777063] unittest_i2c_bus_remove+0x8c/0x91
[ 1324.778802] platform_drv_remove+0x6b/0x86
[ 1324.780244] really_probe+0x556/0xa6a
[ 1324.781460] ? device_driver_attach+0x11c/0x11c
[ 1324.782846] driver_probe_device+0x255/0x2ae
[ 1324.788548] ? device_driver_attach+0x11c/0x11c
[ 1324.797095] device_driver_attach+0xe1/0x11c
[ 1324.805654] __driver_attach+0x212/0x21c
[ 1324.813342] ? do_raw_spin_unlock+0x5f/0x1a9
[ 1324.821667] bus_for_each_dev+0x100/0x13f
[ 1324.829346] ? bus_remove_file+0x5c/0x5c
[ 1324.837060] bus_add_driver+0x2f8/0x48f
[ 1324.844575] driver_register+0x263/0x2d7
[ 1324.852455] of_unittest_overlay+0xa24/0xdf3
[ 1324.860814] ? of_unittest_apply_overlay_check+0x185/0x185
[ 1324.871379] ? preempt_count_sub+0x168/0x176
[ 1324.879610] ? _raw_spin_unlock_irqrestore+0xb8/0xcc
[ 1324.889234] of_unittest+0x1928/0x220d
[ 1324.896690] ? of_unittest_overlay+0xdf3/0xdf3
[ 1324.905563] ? kfree+0x167/0x1a1
[ 1324.911954] ? kfree+0x167/0x1a1
[ 1324.918716] ? kobject_uevent_env+0xa76/0xb19
[ 1324.927239] ? kobject_uevent_env+0xa76/0xb19
[ 1324.935634] ? bus_add_driver+0x3ef/0x48f
[ 1324.943481] ? of_unittest_overlay+0xdf3/0xdf3
[ 1324.951937] do_one_initcall+0x101/0x394
[ 1324.959678] ? start_kernel+0x79a/0x79a
[ 1324.967038] ? lock_downgrade+0x519/0x558
[ 1324.974789] kernel_init_freeable+0x3e3/0x549
[ 1324.983279] ? rest_init+0x165/0x165
[ 1324.989951] kernel_init+0xc/0x108
[ 1324.996664] ? rest_init+0x165/0x165
[ 1325.003431] ret_from_fork+0x24/0x30
[ 1325.010400] ---[ end trace 58c9c5f1671602e0 ]---
[ 1325.045816] kobject: 'i2c-dev' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1325.059371] kobject: 'i2c-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1325.063368] kobject: 'i2c-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1325.429905] kobject: 'input2' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250)
[ 1326.273766] kobject: 'test-unittest111' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1327.486526] kobject: 'test-unittest11' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1329.314442] _warn_unseeded_randomness: 6 callbacks suppressed
[ 1329.314510] random: get_random_u32 called from cache_grow_begin+0x244/0x43f with crng_init=0
[ 1329.474562] i2c i2c-0: Added multiplexed i2c bus 1
[ 1329.499120] random: get_random_u32 called from kobject_put+0x8d/0x22d with crng_init=0
[ 1329.514579] kobject: '1-0020' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500)
[ 1329.531762] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11
[ 1329.552236] kobject: '(null)' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1329.569868] ------------[ cut here ]------------
[ 1329.578490] ODEBUG: free active (active state 0) object type: timer_list hint: delayed_work_timer_fn+0x0/0x6b
[ 1329.596372] WARNING: CPU: 0 PID: 1 at lib/debugobjects.c:328 debug_print_object+0x169/0x181
[ 1329.614753] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G B W 5.2.0-rc1-00387-g47cdee29 #1
[ 1329.626605] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 1329.630068] RIP: 0010:debug_print_object+0x169/0x181
[ 1329.631751] Code: 3c 02 00 74 05 e8 f1 39 48 ff 48 8b 14 dd 60 68 2f 84 4d 89 e9 49 89 e8 44 89 f1 4c 89 e6 48 c7 c7 c0 5a 2f 84 e8 ce f9 12 ff <0f> 0b 5b ff 05 bb f7 9b 03 5d 41 5c 41 5d 41 5e c3 ff 05 ad f7 9b
[ 1329.652450] RSP: 0000:ffff88800ff675f8 EFLAGS: 00010092
[ 1329.661652] RAX: 0000000000000000 RBX: 0000000000000003 RCX: 0000000000000000
[ 1329.669941] RDX: 1ffffffff0dde404 RSI: 0000000000000004 RDI: ffffed1001feceb4
[ 1329.672829] RBP: ffffffff8408fb00 R08: fffffbfff09eac51 R09: fffffbfff09eac50
[ 1329.675255] R10: ffffed1001feceb4 R11: 0000000000000000 R12: ffffffff842f5e40
[ 1329.677299] R13: ffffffff8118ec80 R14: 0000000000000000 R15: 0000000000000000
[ 1329.687450] FS: 0000000000000000(0000) GS:ffff888019800000(0000) knlGS:0000000000000000
[ 1329.701806] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1329.711942] CR2: 00007ffc4dc4ef88 CR3: 000000001430a003 CR4: 00000000000206b0
[ 1329.724549] Call Trace:
[ 1329.729054] __debug_check_no_obj_freed+0x165/0x30d
[ 1329.737966] kfree+0x116/0x1a1
[ 1329.743737] i2cdev_detach_adapter+0x8f/0xf3
[ 1329.751778] notifier_call_chain+0xae/0xf4
[ 1329.759257] __blocking_notifier_call_chain+0x78/0x90
[ 1329.768304] device_del+0x178/0x7f5
[ 1329.774719] ? __pm_runtime_disable+0x22e/0x256
[ 1329.783044] ? klist_children_put+0x39/0x39
[ 1329.790885] ? lockdep_init_map+0x216/0x5af
[ 1329.797357] ? lockdep_init_map+0x216/0x5af
[ 1329.799710] device_unregister+0x83/0x8c
[ 1329.801359] i2c_del_adapter+0x489/0x501
[ 1329.802795] i2c_mux_del_adapters+0x18f/0x1dc
[ 1329.804373] ? i2c_mux_add_adapter+0xca3/0xca3
[ 1329.805808] ? unittest_i2c_mux_remove+0x90/0x90
[ 1329.813495] ? acpi_dev_pm_attach+0x43/0x136
[ 1329.821464] unittest_i2c_mux_remove+0x8b/0x90
[ 1329.829893] i2c_device_remove+0xc3/0x1b8
[ 1329.837384] really_probe+0x556/0xa6a
[ 1329.843671] ? device_driver_attach+0x11c/0x11c
[ 1329.850363] driver_probe_device+0x255/0x2ae
[ 1329.852398] ? device_driver_attach+0x11c/0x11c
[ 1329.854249] device_driver_attach+0xe1/0x11c
[ 1329.855822] __driver_attach+0x212/0x21c
[ 1329.857182] ? do_raw_spin_unlock+0x5f/0x1a9
[ 1329.858511] bus_for_each_dev+0x100/0x13f
[ 1329.865749] ? bus_remove_file+0x5c/0x5c
[ 1329.872595] bus_add_driver+0x2f8/0x48f
[ 1329.879591] driver_register+0x263/0x2d7
[ 1329.886910] i2c_register_driver+0xd3/0x12c
[ 1329.894857] of_unittest_overlay+0xb36/0xdf3
[ 1329.902275] ? of_unittest_apply_overlay_check+0x185/0x185
[ 1329.912517] ? preempt_count_sub+0x168/0x176
[ 1329.920362] ? _raw_spin_unlock_irqrestore+0xb8/0xcc
[ 1329.928723] of_unittest+0x1928/0x220d
[ 1329.934515] ? of_unittest_overlay+0xdf3/0xdf3
[ 1329.942424] ? kfree+0x167/0x1a1
[ 1329.947803] ? kfree+0x167/0x1a1
[ 1329.954128] ? kobject_uevent_env+0xa76/0xb19
[ 1329.962263] ? kobject_uevent_env+0xa76/0xb19
[ 1329.970429] ? bus_add_driver+0x3ef/0x48f
[ 1329.973384] ? of_unittest_overlay+0xdf3/0xdf3
[ 1329.975442] do_one_initcall+0x101/0x394
[ 1329.977027] ? start_kernel+0x79a/0x79a
[ 1329.978355] ? lock_downgrade+0x519/0x558
[ 1329.979525] kernel_init_freeable+0x3e3/0x549
[ 1329.980823] ? rest_init+0x165/0x165
[ 1329.987391] kernel_init+0xc/0x108
[ 1329.993871] ? rest_init+0x165/0x165
[ 1330.000068] ret_from_fork+0x24/0x30
[ 1330.006617] ---[ end trace 58c9c5f1671602e1 ]---
[ 1330.029282] kobject: 'i2c-dev' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1330.053778] kobject: 'i2c-1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000)
[ 1330.074985] kobject: 'i2c-1' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750)
[ 1330.550325] Writes: Total: 34055267 Max/Min: 0/0 Fail: 0
[ 1330.550651] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11/test-unittest111

Elapsed time: 1320

kvm=(
qemu-system-x86_64
-enable-kvm
-cpu Haswell,+smep,+smap
-kernel $kernel
-initrd /osimage/yocto/yocto-trinity-x86_64.cgz
-m 512
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-watchdog-action debug
-rtc base=localtime
-serial stdio
-display none
-monitor null
)

append=(
root=/dev/ram0
hung_task_panic=1
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
net.ifnames=0
printk.devkmsg=on
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
drbd.minor_count=8
systemd.log_level=err
ignore_loglevel
console=tty0
earlyprintk=ttyS0,115200
console=ttyS0,115200
vga=normal
rw
branch=linux-devel/fixup-47cdee29ef9d94e485eb08f962c74943023a5271
BOOT_IMAGE=/pkg/linux/x86_64-randconfig-ne0-06030921+CONFIG_KASAN/gcc-6/47cdee29ef9d94e485eb08f962c74943023a5271/vmlinuz-5.2.0-rc1-00387-g47cdee29
drbd.minor_count=8
rcuperf.shutdown=0
)

"${kvm[@]}" -append "${append[*]}"

Next message: Yu-Hsuan Hsu: "[PATCH] ASoC: max98090: remove 24-bit format support if RJ is 0"
Previous message: Steven Rostedt: "Re: [PATCH] kprobes: Fix to init kprobes in subsys_initcall"
In reply to: Ming Lei: "Re: [block] 47cdee29ef: BUG:kernel_NULL_pointer_dereference,address"
Next in thread: Ming Lei: "Re: [block] 47cdee29ef: BUG:kernel_NULL_pointer_dereference,address"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]