LKML Archive on lore.kernel.org
help / color / mirror / Atom feed
From: Rong Chen <rong.a.chen@intel.com>
To: Ming Lei <ming.lei@redhat.com>
Cc: Jens Axboe <axboe@kernel.dk>,
Bart Van Assche <bvanassche@acm.org>,
Christoph Hellwig <hch@lst.de>,
LKML <linux-kernel@vger.kernel.org>,
Linus Torvalds <torvalds@linux-foundation.org>,
lkp@01.org
Subject: Re: [block] 47cdee29ef: BUG:kernel_NULL_pointer_dereference,address
Date: Tue, 4 Jun 2019 17:06:44 +0800 [thread overview]
Message-ID: <de04afaf-5e17-7999-0f10-3da466c66310@intel.com> (raw)
In-Reply-To: <20190604040343.GB7208@ming.t460p>
[-- Attachment #1: Type: text/plain, Size: 3442 bytes --]
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@intel.com>
>>
>>
>> [ 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
[-- Attachment #2: dmesg-yocto-vm-yocto-202:20190604165150:x86_64-randconfig-ne0-06030921+CONFIG_KASAN:5.2.0-rc1-00387-g47cdee29:1 --]
[-- Type: text/plain, Size: 204973 bytes --]
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@linux.it>
[ 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@linux.org.
[ 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 prev parent reply other threads:[~2019-06-04 9:06 UTC|newest]
Thread overview: 5+ messages / expand[flat|nested] mbox.gz Atom feed top
2019-06-04 2:09 kernel test robot
2019-06-04 4:03 ` Ming Lei
2019-06-04 9:06 ` Rong Chen [this message]
2019-06-04 10:43 ` Ming Lei
2019-06-04 13:11 ` Ming Lei
Reply instructions:
You may reply publicly to this message via plain-text email
using any one of the following methods:
* Save the following mbox file, import it into your mail client,
and reply-to-all from there: mbox
Avoid top-posting and favor interleaved quoting:
https://en.wikipedia.org/wiki/Posting_style#Interleaved_style
* Reply using the --to, --cc, and --in-reply-to
switches of git-send-email(1):
git send-email \
--in-reply-to=de04afaf-5e17-7999-0f10-3da466c66310@intel.com \
--to=rong.a.chen@intel.com \
--cc=axboe@kernel.dk \
--cc=bvanassche@acm.org \
--cc=hch@lst.de \
--cc=linux-kernel@vger.kernel.org \
--cc=lkp@01.org \
--cc=ming.lei@redhat.com \
--cc=torvalds@linux-foundation.org \
--subject='Re: [block] 47cdee29ef: BUG:kernel_NULL_pointer_dereference,address' \
/path/to/YOUR_REPLY
https://kernel.org/pub/software/scm/git/docs/git-send-email.html
* If your mail client supports setting the In-Reply-To header
via mailto: links, try the mailto: link
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).