high speed data to usb disk makes the kernel think that is has been unmounted

high speed data to usb disk makes the kernel think that is has been unmounted

[Patrik Nilsson]

Hi!

I have an issue with a usb to sata adapter when much high speed data is 
sent. If I erase my 4Tb SSD disk connected to the usb to sata interface 
with a "slower" /dev/urandom it works, but if I exchanges this to 
/dev/zero the disk is after a while disconnected.

For logs from udev and more debug attempts see: 
https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1886172

Description:

When the speed of data with a usb disk device is too high, the probes 
that check if the disk still exists is missed. This makes the disk to be 
unmounted and is remounted with an other drive letter (/dev/sdX). If 
this disk is the root ("/"), the computer hangs.

A usb SSD-disk (4 TB) connected to a USB port reproduce this bug easily. 
This has never happened with a mechanical USB-drive.

Symptoms and how to reproduce:

1) (Disk mounted as root) Computer hangs. Only way to get started again 
is a hard reset.

2) (Erasing disk, not mounted as root) If you erase a disk with dd ("dd 
if=/dev/zero of=/dev/sdX"), the disk disappears from the mounted list 
and reappears as an other drive letter. In my case the data is 
redirected to /dev/null and the fans speed up. The apparent data 
transfer speed is also increased. Usually this happens after about 45 to 
60 minutes.

3) Fill RAM with programs and make sure the swap to this disk is used.

Bug found in:

$ uname -a
Linux pb-189b1884 5.4.0-40-generic #44-Ubuntu SMP Tue Jun 23 00:01:04 
UTC 2020 x86_64 x86_64 x86_64 GNU/Linux

$ cat /proc/version_signature
Ubuntu 5.4.0-40.44-generic 5.4.44

Hardware:

Tested with slower and faster computers. Tested directly connected to a 
computer and via a hub. They all reproduce this bug.

Workaround:

None found.

Best regards,
Patrik

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Greg KH]

On Tue, Aug 11, 2020 at 07:19:00PM +0200, Patrik Nilsson wrote:
> Hi!
> 
> I have an issue with a usb to sata adapter when much high speed data is
> sent. If I erase my 4Tb SSD disk connected to the usb to sata interface with
> a "slower" /dev/urandom it works, but if I exchanges this to /dev/zero the
> disk is after a while disconnected.
> 
> For logs from udev and more debug attempts see:
> https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1886172
> 
> Description:
> 
> When the speed of data with a usb disk device is too high, the probes that
> check if the disk still exists is missed. This makes the disk to be
> unmounted and is remounted with an other drive letter (/dev/sdX). If this
> disk is the root ("/"), the computer hangs.
> 
> A usb SSD-disk (4 TB) connected to a USB port reproduce this bug easily.
> This has never happened with a mechanical USB-drive.
> 
> Symptoms and how to reproduce:
> 
> 1) (Disk mounted as root) Computer hangs. Only way to get started again is a
> hard reset.
> 
> 2) (Erasing disk, not mounted as root) If you erase a disk with dd ("dd
> if=/dev/zero of=/dev/sdX"), the disk disappears from the mounted list and
> reappears as an other drive letter. In my case the data is redirected to
> /dev/null and the fans speed up. The apparent data transfer speed is also
> increased. Usually this happens after about 45 to 60 minutes.

Can you provide kernel logs?  Odds are the disk disconnects itself as it
takes too much power or something.

> 3) Fill RAM with programs and make sure the swap to this disk is used.
> 
> Bug found in:
> 
> $ uname -a
> Linux pb-189b1884 5.4.0-40-generic #44-Ubuntu SMP Tue Jun 23 00:01:04 UTC
> 2020 x86_64 x86_64 x86_64 GNU/Linux
> 
> $ cat /proc/version_signature
> Ubuntu 5.4.0-40.44-generic 5.4.44

That's a really old kernel, any chance you can try 5.8?

thanks,

greg k-h

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Patrik Nilsson]

Hi Greg,
> Can you provide kernel logs?  Odds are the disk disconnects itself as it
> takes too much power or something.
udev-kernel logs can be found: 
https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1886172

I have tested the usb to sata adapter directly connected to a computer 
and connected to a externally powered usb hub. The bug behavior is the same.

One time when I tested the whole usb hub disconnected. I have a usb to 
ethernet connected to it too. It vanished and appeared again. Doubt it 
is power. The externally powered hub can deliver 4A, but I hope the usb 
to sata only takes 2A.

> That's a really old kernel, any chance you can try 5.8?
I'm downloading from 
git://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git. Will 
test it soon.

Best regards,
Patrik

On 11/08/2020 19:56, Greg KH wrote:
> On Tue, Aug 11, 2020 at 07:19:00PM +0200, Patrik Nilsson wrote:
>> Hi!
>>
>> I have an issue with a usb to sata adapter when much high speed data is
>> sent. If I erase my 4Tb SSD disk connected to the usb to sata interface with
>> a "slower" /dev/urandom it works, but if I exchanges this to /dev/zero the
>> disk is after a while disconnected.
>>
>> For logs from udev and more debug attempts see:
>> https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1886172
>>
>> Description:
>>
>> When the speed of data with a usb disk device is too high, the probes that
>> check if the disk still exists is missed. This makes the disk to be
>> unmounted and is remounted with an other drive letter (/dev/sdX). If this
>> disk is the root ("/"), the computer hangs.
>>
>> A usb SSD-disk (4 TB) connected to a USB port reproduce this bug easily.
>> This has never happened with a mechanical USB-drive.
>>
>> Symptoms and how to reproduce:
>>
>> 1) (Disk mounted as root) Computer hangs. Only way to get started again is a
>> hard reset.
>>
>> 2) (Erasing disk, not mounted as root) If you erase a disk with dd ("dd
>> if=/dev/zero of=/dev/sdX"), the disk disappears from the mounted list and
>> reappears as an other drive letter. In my case the data is redirected to
>> /dev/null and the fans speed up. The apparent data transfer speed is also
>> increased. Usually this happens after about 45 to 60 minutes.
> Can you provide kernel logs?  Odds are the disk disconnects itself as it
> takes too much power or something.
>
>> 3) Fill RAM with programs and make sure the swap to this disk is used.
>>
>> Bug found in:
>>
>> $ uname -a
>> Linux pb-189b1884 5.4.0-40-generic #44-Ubuntu SMP Tue Jun 23 00:01:04 UTC
>> 2020 x86_64 x86_64 x86_64 GNU/Linux
>>
>> $ cat /proc/version_signature
>> Ubuntu 5.4.0-40.44-generic 5.4.44
> That's a really old kernel, any chance you can try 5.8?
>
> thanks,
>
> greg k-h

-- 
PGP-key fingerprint: 1B30 7F61 AF9E 538A FCD6  2BE7 CED7 B0E4 3BF9 8D6C

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Greg KH]

On Tue, Aug 11, 2020 at 08:34:53PM +0200, Patrik Nilsson wrote:
> Hi Greg,
> > Can you provide kernel logs?  Odds are the disk disconnects itself as it
> > takes too much power or something.
> udev-kernel logs can be found:
> https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1886172

udev logs do not help, can you do, from a command line:
	sudo dmesg -c		# clears the kernel log
	<do your testing>
	dmesg > kernel_log.txt

and send us the kernel_log.txt file?

> I have tested the usb to sata adapter directly connected to a computer and
> connected to a externally powered usb hub. The bug behavior is the same.

I bet the adapter can't handle the issue here, writing a lot of data to
flash does take a lot of power.

> One time when I tested the whole usb hub disconnected. I have a usb to
> ethernet connected to it too. It vanished and appeared again. Doubt it is
> power. The externally powered hub can deliver 4A, but I hope the usb to sata
> only takes 2A.

If the whole hub disconnected, that is a _huge_ hint it's a power issue,
the operating system can not do that on its own.

thanks,

greg k-h

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Alan Stern]

On Tue, Aug 11, 2020 at 08:34:53PM +0200, Patrik Nilsson wrote:
> Hi Greg,
> > Can you provide kernel logs?  Odds are the disk disconnects itself as it
> > takes too much power or something.
> udev-kernel logs can be found:
> https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1886172

The bug report doesn't seem to contain any dmesg kernel logs showing 
what happens when the problem occurs.

Also, the bug report indicates that you started to do a bisection 
looking for the kernel version where the problem first appears, but 
there's no mention of the final result.

The bug report also indicates that one of your tests involves copying 
/dev/zero to a mounted disk device!  That is highly likely to crash the 
kernel regardless of what the hardware does.  What happens if you 
unmount the disk before running the test?

Alan Stern

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Patrik Nilsson]

On 11/08/2020 21:31, Alan Stern wrote:
> On Tue, Aug 11, 2020 at 08:34:53PM +0200, Patrik Nilsson wrote:
>> Hi Greg,
>>> Can you provide kernel logs?  Odds are the disk disconnects itself as it
>>> takes too much power or something.
>> udev-kernel logs can be found:
>> https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1886172
> The bug report doesn't seem to contain any dmesg kernel logs showing
> what happens when the problem occurs.
I'm compiling the latest kernel to supply the kernel logs when done 
testing. It takes a while.
>
> Also, the bug report indicates that you started to do a bisection
> looking for the kernel version where the problem first appears, but
> there's no mention of the final result.

There were no result. For an unknown reason I haven't the bug behavior 
in the 4.15 series of the kernel (Ubuntu Mate Bionic), but when I tried 
later I had. Maybe it was when I ran Ubuntu Mate Focal with 4.15 series 
of kernel that triggered the bug.

It was a failed attempt to iterate to the kernel version that worked.

It is the same hardware.

> The bug report also indicates that one of your tests involves copying
> /dev/zero to a mounted disk device!  That is highly likely to crash the
> kernel regardless of what the hardware does.  What happens if you
> unmount the disk before running the test?
The ssd disk contains no partition data. It is not mounted.
> Alan Stern
Patrik

-- 
PGP-key fingerprint: 1B30 7F61 AF9E 538A FCD6  2BE7 CED7 B0E4 3BF9 8D6C

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Greg KH]

On Tue, Aug 11, 2020 at 11:14:09PM +0200, Patrik Nilsson wrote:
> 
> On 11/08/2020 21:31, Alan Stern wrote:
> > On Tue, Aug 11, 2020 at 08:34:53PM +0200, Patrik Nilsson wrote:
> > > Hi Greg,
> > > > Can you provide kernel logs?  Odds are the disk disconnects itself as it
> > > > takes too much power or something.
> > > udev-kernel logs can be found:
> > > https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1886172
> > The bug report doesn't seem to contain any dmesg kernel logs showing
> > what happens when the problem occurs.
> I'm compiling the latest kernel to supply the kernel logs when done testing.
> It takes a while.

'make localmodconfig' is your friend.  Should make a kernel that builds
in 5-10 minutes at most depending on your system.

thanks,

greg k-h

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Patrik Nilsson]

[-- Attachment #1: Type: text/plain, Size: 1991 bytes --]

Hi Greg,

A lot of lengthy testing and the result seem promising. Here is 
something to think about and why the result is as it is.

Three kernels was tested with and without 
'GRUB_CMDLINE_LINUX_DEFAULT="usb-storage.quirks=174c:55aa:u"'. This is 
shortened with and without quirks.

The following kernel fails WITH quirks: (all tested)
Linux 5.8.0+ #1 SMP Tue Aug 11 21:31:01 CEST 2020 x86_64 x86_64 x86_64 
GNU/Linux (dmesg attached)
Linux 5.4.0-39-generic #43-Ubuntu SMP Fri Jun 19 10:28:31 UTC 2020 
x86_64 x86_64 x86_64 GNU/Linux
Linux 5.4.0-37-generic #41-Ubuntu SMP Wed Jun 3 18:57:02 UTC 2020 x86_64 
x86_64 x86_64 GNU/Linux

The following kernel fails WITHOUT quirks:
Linux 5.4.0-37-generic #41-Ubuntu SMP Wed Jun 3 18:57:02 UTC 2020 x86_64 
x86_64 x86_64 GNU/Linux

The following kernels succeed WITHOUT quirks:
Linux 5.8.0+ #1 SMP Tue Aug 11 21:31:01 CEST 2020 x86_64 x86_64 x86_64 
GNU/Linux
Linux 5.4.0-39-generic #43-Ubuntu SMP Fri Jun 19 10:28:31 UTC 2020 
x86_64 x86_64 x86_64 GNU/Linux

The same hardware and Ubuntu Mate Focal was used without modification.

Best regards,
Patrik

On 12/08/2020 08:14, Greg KH wrote:
> On Tue, Aug 11, 2020 at 11:14:09PM +0200, Patrik Nilsson wrote:
>> On 11/08/2020 21:31, Alan Stern wrote:
>>> On Tue, Aug 11, 2020 at 08:34:53PM +0200, Patrik Nilsson wrote:
>>>> Hi Greg,
>>>>> Can you provide kernel logs?  Odds are the disk disconnects itself as it
>>>>> takes too much power or something.
>>>> udev-kernel logs can be found:
>>>> https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1886172
>>> The bug report doesn't seem to contain any dmesg kernel logs showing
>>> what happens when the problem occurs.
>> I'm compiling the latest kernel to supply the kernel logs when done testing.
>> It takes a while.
> 'make localmodconfig' is your friend.  Should make a kernel that builds
> in 5-10 minutes at most depending on your system.
>
> thanks,
>
> greg k-h

-- 
PGP-key fingerprint: 1B30 7F61 AF9E 538A FCD6  2BE7 CED7 B0E4 3BF9 8D6C


[-- Attachment #2: dmesg8.txt --]
[-- Type: text/plain, Size: 75904 bytes --]

[    0.000000] microcode: microcode updated early to revision 0x26, date = 2019-11-12
[    0.000000] Linux version 5.8.0+ (user@kgxup-fb87d396) (gcc (Ubuntu 9.3.0-10ubuntu2) 9.3.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1 SMP Tue Aug 11 21:31:01 CEST 2020
[    0.000000] Command line: BOOT_IMAGE=/vmlinuz-5.8.0+ root=UUID=493e5822-b9e4-11ea-95ff-43291c87edcb ro fsck.repair=yes usb-storage.quirks=174c:55aa:u
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Hygon HygonGenuine
[    0.000000]   Centaur CentaurHauls
[    0.000000]   zhaoxin   Shanghai  
[    0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[    0.000000] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
[    0.000000] BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x0000000000057fff] usable
[    0.000000] BIOS-e820: [mem 0x0000000000058000-0x0000000000058fff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000059000-0x0000000000097fff] usable
[    0.000000] BIOS-e820: [mem 0x0000000000098000-0x00000000000bffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000dbfb8fff] usable
[    0.000000] BIOS-e820: [mem 0x00000000dbfb9000-0x00000000dbfb9fff] ACPI NVS
[    0.000000] BIOS-e820: [mem 0x00000000dbfba000-0x00000000dbfccfff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000dbfcd000-0x00000000dc0b8fff] ACPI NVS
[    0.000000] BIOS-e820: [mem 0x00000000dc0b9000-0x00000000dc0e8fff] ACPI data
[    0.000000] BIOS-e820: [mem 0x00000000dc0e9000-0x00000000dcffefff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000dcfff000-0x00000000dcffffff] usable
[    0.000000] BIOS-e820: [mem 0x00000000dd000000-0x00000000df9fffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000f8000000-0x00000000fbffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fe101000-0x00000000fe112fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed10000-0x00000000fed13fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed18000-0x00000000fed19fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000041f5fffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] e820: update [mem 0xd7c6c018-0xd7c7c057] usable ==> usable
[    0.000000] e820: update [mem 0xd7c6c018-0xd7c7c057] usable ==> usable
[    0.000000] e820: update [mem 0xa1e5a018-0xa1e6a057] usable ==> usable
[    0.000000] e820: update [mem 0xa1e5a018-0xa1e6a057] usable ==> usable
[    0.000000] extended physical RAM map:
[    0.000000] reserve setup_data: [mem 0x0000000000000000-0x0000000000057fff] usable
[    0.000000] reserve setup_data: [mem 0x0000000000058000-0x0000000000058fff] reserved
[    0.000000] reserve setup_data: [mem 0x0000000000059000-0x0000000000097fff] usable
[    0.000000] reserve setup_data: [mem 0x0000000000098000-0x00000000000bffff] reserved
[    0.000000] reserve setup_data: [mem 0x0000000000100000-0x00000000a1e5a017] usable
[    0.000000] reserve setup_data: [mem 0x00000000a1e5a018-0x00000000a1e6a057] usable
[    0.000000] reserve setup_data: [mem 0x00000000a1e6a058-0x00000000d7c6c017] usable
[    0.000000] reserve setup_data: [mem 0x00000000d7c6c018-0x00000000d7c7c057] usable
[    0.000000] reserve setup_data: [mem 0x00000000d7c7c058-0x00000000dbfb8fff] usable
[    0.000000] reserve setup_data: [mem 0x00000000dbfb9000-0x00000000dbfb9fff] ACPI NVS
[    0.000000] reserve setup_data: [mem 0x00000000dbfba000-0x00000000dbfccfff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000dbfcd000-0x00000000dc0b8fff] ACPI NVS
[    0.000000] reserve setup_data: [mem 0x00000000dc0b9000-0x00000000dc0e8fff] ACPI data
[    0.000000] reserve setup_data: [mem 0x00000000dc0e9000-0x00000000dcffefff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000dcfff000-0x00000000dcffffff] usable
[    0.000000] reserve setup_data: [mem 0x00000000dd000000-0x00000000df9fffff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000f8000000-0x00000000fbffffff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000fe101000-0x00000000fe112fff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000fec00000-0x00000000fec00fff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000fed10000-0x00000000fed13fff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000fed18000-0x00000000fed19fff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000fee00000-0x00000000fee00fff] reserved
[    0.000000] reserve setup_data: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved
[    0.000000] reserve setup_data: [mem 0x0000000100000000-0x000000041f5fffff] usable
[    0.000000] efi: EFI v2.31 by TOSHIBA
[    0.000000] efi: ACPI=0xdc0e8000 ACPI 2.0=0xdc0e8014 SMBIOS=0xdc2a2e98 
[    0.000000] SMBIOS 2.6 present.
[    0.000000] DMI: TOSHIBA SATELLITE Z30-A/SATELLITE Z30-A, BIOS Version 3.80   09/19/2014
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] tsc: Detected 2593.924 MHz processor
[    0.001749] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.001751] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.001760] last_pfn = 0x41f600 max_arch_pfn = 0x400000000
[    0.001765] MTRR default type: uncachable
[    0.001766] MTRR fixed ranges enabled:
[    0.001767]   00000-9FFFF write-back
[    0.001769]   A0000-DFFFF uncachable
[    0.001770]   E0000-FFFFF write-through
[    0.001770] MTRR variable ranges enabled:
[    0.001772]   0 base 00FFC00000 mask 7FFFC00000 uncachable
[    0.001773]   1 base 0000000000 mask 7F80000000 write-back
[    0.001774]   2 base 0080000000 mask 7FC0000000 write-back
[    0.001775]   3 base 00C0000000 mask 7FE0000000 write-back
[    0.001776]   4 base 00DE000000 mask 7FFE000000 uncachable
[    0.001777]   5 base 00DD800000 mask 7FFF800000 uncachable
[    0.001778]   6 base 0100000000 mask 7F00000000 write-back
[    0.001779]   7 base 0200000000 mask 7F00000000 write-back
[    0.001780]   8 base 0300000000 mask 7F00000000 write-back
[    0.001781]   9 base 0400000000 mask 7FE0000000 write-back
[    0.002158] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.002645] total RAM covered: 16344M
[    0.003148] Found optimal setting for mtrr clean up
[    0.003150]  gran_size: 64K 	chunk_size: 64M 	num_reg: 8  	lose cover RAM: 0G
[    0.003522] e820: update [mem 0xdd800000-0xffffffff] usable ==> reserved
[    0.003526] last_pfn = 0xdd000 max_arch_pfn = 0x400000000
[    0.014010] check: Scanning 1 areas for low memory corruption
[    0.014016] Using GB pages for direct mapping
[    0.014434] Secure boot disabled
[    0.014435] RAMDISK: [mem 0x3b09a000-0x3fffdfff]
[    0.014444] ACPI: Early table checksum verification disabled
[    0.014448] ACPI: RSDP 0x00000000DC0E8014 000024 (v02 TOSHIB)
[    0.014452] ACPI: XSDT 0x00000000DC0E8148 0000B4 (v01 TOSHIB A008E    00000003      01000013)
[    0.014459] ACPI: FACP 0x00000000DC0E5000 00010C (v05 TOSHIB A008E    00000003 LOHR 0000005F)
[    0.014466] ACPI: DSDT 0x00000000DC0C9000 011CE9 (v02 TOSHIB A008E    20140918 INTL 20120518)
[    0.014470] ACPI: FACS 0x00000000DC0A6000 000040
[    0.014473] ACPI: FACS 0x00000000DC0A6000 000040
[    0.014477] ACPI: HPET 0x00000000DC0E4000 000038 (v01 TOSHIB A008E    00000001 LOHR 0000005F)
[    0.014480] ACPI: APIC 0x00000000DC0E3000 0000BC (v01 TOSHIB A008E    00000001 LOHR 0000005F)
[    0.014484] ACPI: MCFG 0x00000000DC0E2000 00003C (v01 TOSHIB A008E    00000001 LOHR 0000005F)
[    0.014488] ACPI: ASF! 0x00000000DC0E1000 0000A0 (v32 TOSHIB A008E    00000001 LOHR 0000005F)
[    0.014492] ACPI: BOOT 0x00000000DC0DF000 000028 (v01 TOSHIB A008E    00000000 LOHR 0000005F)
[    0.014495] ACPI: MSDM 0x00000000DC0DE000 000055 (v03 TOSHIB A008E    00000000 LOHR 0000005F)
[    0.014499] ACPI: SLIC 0x00000000DC0DD000 000176 (v01 TOSHIB A008E    00000000 LOHR 0000005F)
[    0.014503] ACPI: DBGP 0x00000000DC0DC000 000034 (v01 TOSHIB A008E    00000000 LOHR 0000005F)
[    0.014506] ACPI: SSDT 0x00000000DC0C8000 0003DD (v01 TOSHIB SataAhci 00001000 INTL 20120518)
[    0.014510] ACPI: SSDT 0x00000000DC0C5000 000720 (v01 TOSHIB PtidDevc 00001000 INTL 20120518)
[    0.014514] ACPI: SSDT 0x00000000DC0C1000 0009B3 (v01 TOSHIB MacUniq2 00001000 INTL 20120518)
[    0.014517] ACPI: SSDT 0x00000000DC0C0000 0005E6 (v01 PmRef  Cpu0Ist  00003000 INTL 20120518)
[    0.014521] ACPI: SSDT 0x00000000DC0BF000 000AC4 (v01 PmRef  CpuPm    00003000 INTL 20120518)
[    0.014525] ACPI: SSDT 0x00000000DC0BE000 000566 (v01 SaSsdt SaSsdt   00003000 INTL 20120518)
[    0.014529] ACPI: SSDT 0x00000000DC0BD000 0003AB (v01 Intel_ IsctTabl 00001000 INTL 20120518)
[    0.014533] ACPI: FPDT 0x00000000DC0DB000 000044 (v01 TOSHIB A008E    00000000 LOHR 0000005F)
[    0.014537] ACPI: BGRT 0x00000000DC0BC000 000038 (v01 TOSHIB A008E    00000000 LOHR 0000005F)
[    0.014547] ACPI: Local APIC address 0xfee00000
[    0.014646] No NUMA configuration found
[    0.014648] Faking a node at [mem 0x0000000000000000-0x000000041f5fffff]
[    0.014661] NODE_DATA(0) allocated [mem 0x41f5c6000-0x41f5f0fff]
[    0.015051] Zone ranges:
[    0.015052]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.015054]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.015056]   Normal   [mem 0x0000000100000000-0x000000041f5fffff]
[    0.015057]   Device   empty
[    0.015059] Movable zone start for each node
[    0.015063] Early memory node ranges
[    0.015065]   node   0: [mem 0x0000000000001000-0x0000000000057fff]
[    0.015066]   node   0: [mem 0x0000000000059000-0x0000000000097fff]
[    0.015067]   node   0: [mem 0x0000000000100000-0x00000000dbfb8fff]
[    0.015069]   node   0: [mem 0x00000000dcfff000-0x00000000dcffffff]
[    0.015070]   node   0: [mem 0x0000000100000000-0x000000041f5fffff]
[    0.015256] Zeroed struct page in unavailable ranges: 19120 pages
[    0.015257] Initmem setup node 0 [mem 0x0000000000001000-0x000000041f5fffff]
[    0.015259] On node 0 totalpages: 4175184
[    0.015261]   DMA zone: 64 pages used for memmap
[    0.015261]   DMA zone: 1164 pages reserved
[    0.015262]   DMA zone: 3990 pages, LIFO batch:0
[    0.015291]   DMA32 zone: 14015 pages used for memmap
[    0.015292]   DMA32 zone: 896954 pages, LIFO batch:63
[    0.021718]   Normal zone: 51160 pages used for memmap
[    0.021719]   Normal zone: 3274240 pages, LIFO batch:63
[    0.045182] Reserving Intel graphics memory at [mem 0xdda00000-0xdf9fffff]
[    0.045263] ACPI: PM-Timer IO Port: 0x1808
[    0.045265] ACPI: Local APIC address 0xfee00000
[    0.045273] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
[    0.045274] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
[    0.045275] ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1])
[    0.045276] ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1])
[    0.045277] ACPI: LAPIC_NMI (acpi_id[0x04] high edge lint[0x1])
[    0.045278] ACPI: LAPIC_NMI (acpi_id[0x05] high edge lint[0x1])
[    0.045279] ACPI: LAPIC_NMI (acpi_id[0x06] high edge lint[0x1])
[    0.045280] ACPI: LAPIC_NMI (acpi_id[0x07] high edge lint[0x1])
[    0.045292] IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-39
[    0.045295] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.045297] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.045299] ACPI: IRQ0 used by override.
[    0.045300] ACPI: IRQ9 used by override.
[    0.045302] Using ACPI (MADT) for SMP configuration information
[    0.045304] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.045319] e820: update [mem 0xd912a000-0xd9147fff] usable ==> reserved
[    0.045330] TSC deadline timer available
[    0.045332] smpboot: Allowing 8 CPUs, 4 hotplug CPUs
[    0.045358] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.045361] PM: hibernation: Registered nosave memory: [mem 0x00058000-0x00058fff]
[    0.045364] PM: hibernation: Registered nosave memory: [mem 0x00098000-0x000bffff]
[    0.045365] PM: hibernation: Registered nosave memory: [mem 0x000c0000-0x000fffff]
[    0.045368] PM: hibernation: Registered nosave memory: [mem 0xa1e5a000-0xa1e5afff]
[    0.045370] PM: hibernation: Registered nosave memory: [mem 0xa1e6a000-0xa1e6afff]
[    0.045373] PM: hibernation: Registered nosave memory: [mem 0xd7c6c000-0xd7c6cfff]
[    0.045376] PM: hibernation: Registered nosave memory: [mem 0xd7c7c000-0xd7c7cfff]
[    0.045378] PM: hibernation: Registered nosave memory: [mem 0xd912a000-0xd9147fff]
[    0.045381] PM: hibernation: Registered nosave memory: [mem 0xdbfb9000-0xdbfb9fff]
[    0.045382] PM: hibernation: Registered nosave memory: [mem 0xdbfba000-0xdbfccfff]
[    0.045383] PM: hibernation: Registered nosave memory: [mem 0xdbfcd000-0xdc0b8fff]
[    0.045385] PM: hibernation: Registered nosave memory: [mem 0xdc0b9000-0xdc0e8fff]
[    0.045386] PM: hibernation: Registered nosave memory: [mem 0xdc0e9000-0xdcffefff]
[    0.045389] PM: hibernation: Registered nosave memory: [mem 0xdd000000-0xdf9fffff]
[    0.045390] PM: hibernation: Registered nosave memory: [mem 0xdfa00000-0xf7ffffff]
[    0.045391] PM: hibernation: Registered nosave memory: [mem 0xf8000000-0xfbffffff]
[    0.045392] PM: hibernation: Registered nosave memory: [mem 0xfc000000-0xfe100fff]
[    0.045393] PM: hibernation: Registered nosave memory: [mem 0xfe101000-0xfe112fff]
[    0.045394] PM: hibernation: Registered nosave memory: [mem 0xfe113000-0xfebfffff]
[    0.045396] PM: hibernation: Registered nosave memory: [mem 0xfec00000-0xfec00fff]
[    0.045397] PM: hibernation: Registered nosave memory: [mem 0xfec01000-0xfed0ffff]
[    0.045398] PM: hibernation: Registered nosave memory: [mem 0xfed10000-0xfed13fff]
[    0.045399] PM: hibernation: Registered nosave memory: [mem 0xfed14000-0xfed17fff]
[    0.045400] PM: hibernation: Registered nosave memory: [mem 0xfed18000-0xfed19fff]
[    0.045401] PM: hibernation: Registered nosave memory: [mem 0xfed1a000-0xfed1bfff]
[    0.045403] PM: hibernation: Registered nosave memory: [mem 0xfed1c000-0xfed1ffff]
[    0.045404] PM: hibernation: Registered nosave memory: [mem 0xfed20000-0xfedfffff]
[    0.045405] PM: hibernation: Registered nosave memory: [mem 0xfee00000-0xfee00fff]
[    0.045406] PM: hibernation: Registered nosave memory: [mem 0xfee01000-0xffbfffff]
[    0.045407] PM: hibernation: Registered nosave memory: [mem 0xffc00000-0xffffffff]
[    0.045410] [mem 0xdfa00000-0xf7ffffff] available for PCI devices
[    0.045412] Booting paravirtualized kernel on bare hardware
[    0.045415] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[    0.045422] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:8 nr_cpu_ids:8 nr_node_ids:1
[    0.045728] percpu: Embedded 56 pages/cpu s192512 r8192 d28672 u262144
[    0.045738] pcpu-alloc: s192512 r8192 d28672 u262144 alloc=1*2097152
[    0.045739] pcpu-alloc: [0] 0 1 2 3 4 5 6 7 
[    0.045775] Built 1 zonelists, mobility grouping on.  Total pages: 4108781
[    0.045776] Policy zone: Normal
[    0.045778] Kernel command line: BOOT_IMAGE=/vmlinuz-5.8.0+ root=UUID=493e5822-b9e4-11ea-95ff-43291c87edcb ro fsck.repair=yes usb-storage.quirks=174c:55aa:u
[    0.047008] Dentry cache hash table entries: 2097152 (order: 12, 16777216 bytes, linear)
[    0.047576] Inode-cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[    0.047672] mem auto-init: stack:off, heap alloc:on, heap free:off
[    0.099612] Memory: 16158096K/16700736K available (14339K kernel code, 2547K rwdata, 5292K rodata, 2596K init, 4956K bss, 542380K reserved, 0K cma-reserved)
[    0.099621] random: get_random_u64 called from __kmem_cache_create+0x31/0x430 with crng_init=0
[    0.099782] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1
[    0.099797] Kernel/User page tables isolation: enabled
[    0.099821] ftrace: allocating 45642 entries in 179 pages
[    0.120413] ftrace: allocated 179 pages with 5 groups
[    0.120564] rcu: Hierarchical RCU implementation.
[    0.120566] rcu: 	RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=8.
[    0.120568] 	Trampoline variant of Tasks RCU enabled.
[    0.120569] 	Rude variant of Tasks RCU enabled.
[    0.120569] 	Tracing variant of Tasks RCU enabled.
[    0.120570] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.120572] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8
[    0.125029] NR_IRQS: 524544, nr_irqs: 760, preallocated irqs: 16
[    0.125402] random: crng done (trusting CPU's manufacturer)
[    0.125432] Console: colour dummy device 80x25
[    0.125801] printk: console [tty0] enabled
[    0.125823] ACPI: Core revision 20200717
[    0.125999] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484882848 ns
[    0.126013] APIC: Switch to symmetric I/O mode setup
[    0.126648] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.146016] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2563d0a1387, max_idle_ns: 440795326301 ns
[    0.146023] Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.84 BogoMIPS (lpj=10375696)
[    0.146028] pid_max: default: 32768 minimum: 301
[    0.157566] LSM: Security Framework initializing
[    0.157584] Yama: becoming mindful.
[    0.157617] AppArmor: AppArmor initialized
[    0.157685] Mount-cache hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.157717] Mountpoint-cache hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.158063] process: using mwait in idle threads
[    0.158069] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024
[    0.158072] Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4
[    0.158077] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[    0.158081] Spectre V2 : Mitigation: Full generic retpoline
[    0.158083] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.158086] Spectre V2 : Enabling Restricted Speculation for firmware calls
[    0.158089] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
[    0.158093] Spectre V2 : User space: Mitigation: STIBP via seccomp and prctl
[    0.158096] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp
[    0.158101] SRBDS: Mitigation: Microcode
[    0.158103] MDS: Mitigation: Clear CPU buffers
[    0.158292] Freeing SMP alternatives memory: 40K
[    0.160989] smpboot: CPU0: Intel(R) Core(TM) i7-4510U CPU @ 2.00GHz (family: 0x6, model: 0x45, stepping: 0x1)
[    0.161148] Performance Events: PEBS fmt2+, Haswell events, 16-deep LBR, full-width counters, Intel PMU driver.
[    0.161181] ... version:                3
[    0.161183] ... bit width:              48
[    0.161184] ... generic registers:      4
[    0.161186] ... value mask:             0000ffffffffffff
[    0.161188] ... max period:             00007fffffffffff
[    0.161190] ... fixed-purpose events:   3
[    0.161192] ... event mask:             000000070000000f
[    0.161234] rcu: Hierarchical SRCU implementation.
[    0.162003] NMI watchdog: Enabled. Permanently consumes one hw-PMU counter.
[    0.162020] smp: Bringing up secondary CPUs ...
[    0.162020] x86: Booting SMP configuration:
[    0.162020] .... node  #0, CPUs:      #1
[    0.162020] MDS CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/mds.html for more details.
[    0.162020]  #2 #3
[    0.164110] smp: Brought up 1 node, 4 CPUs
[    0.164110] smpboot: Max logical packages: 2
[    0.164110] smpboot: Total of 4 processors activated (20751.39 BogoMIPS)
[    0.166517] devtmpfs: initialized
[    0.166517] x86/mm: Memory block size: 128MB
[    0.167519] PM: Registering ACPI NVS region [mem 0xdbfb9000-0xdbfb9fff] (4096 bytes)
[    0.167519] PM: Registering ACPI NVS region [mem 0xdbfcd000-0xdc0b8fff] (966656 bytes)
[    0.167519] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.167519] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)
[    0.167519] pinctrl core: initialized pinctrl subsystem
[    0.167519] PM: RTC time: 08:22:01, date: 2020-08-12
[    0.167519] NET: Registered protocol family 16
[    0.167519] DMA: preallocated 2048 KiB GFP_KERNEL pool for atomic allocations
[    0.167519] DMA: preallocated 2048 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.167519] DMA: preallocated 2048 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.167519] audit: initializing netlink subsys (disabled)
[    0.167519] audit: type=2000 audit(1597220521.040:1): state=initialized audit_enabled=0 res=1
[    0.167519] thermal_sys: Registered thermal governor 'fair_share'
[    0.167519] thermal_sys: Registered thermal governor 'bang_bang'
[    0.167519] thermal_sys: Registered thermal governor 'step_wise'
[    0.167519] thermal_sys: Registered thermal governor 'user_space'
[    0.167519] EISA bus registered
[    0.167519] cpuidle: using governor ladder
[    0.167519] cpuidle: using governor menu
[    0.167519] Simple Boot Flag at 0x7c set to 0x1
[    0.167519] ACPI: bus type PCI registered
[    0.167519] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.167519] PCI: MMCONFIG for domain 0000 [bus 00-3f] at [mem 0xf8000000-0xfbffffff] (base 0xf8000000)
[    0.167519] PCI: MMCONFIG at [mem 0xf8000000-0xfbffffff] reserved in E820
[    0.167519] PCI: Using configuration type 1 for base access
[    0.167519] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on
[    0.170029] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.171548] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.171548] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.171548] fbcon: Taking over console
[    0.171548] ACPI: Added _OSI(Module Device)
[    0.171548] ACPI: Added _OSI(Processor Device)
[    0.171548] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.171548] ACPI: Added _OSI(Processor Aggregator Device)
[    0.171548] ACPI: Added _OSI(Linux-Dell-Video)
[    0.171548] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    0.171548] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    0.193920] ACPI: 8 ACPI AML tables successfully acquired and loaded
[    0.195342] ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored
[    0.198912] ACPI: Dynamic OEM Table Load:
[    0.198923] ACPI: SSDT 0xFFFF96E4CCEBD400 0003D3 (v01 PmRef  Cpu0Cst  00003001 INTL 20120518)
[    0.200562] ACPI: Dynamic OEM Table Load:
[    0.200572] ACPI: SSDT 0xFFFF96E4CCB6F000 0005AA (v01 PmRef  ApIst    00003000 INTL 20120518)
[    0.202278] ACPI: Dynamic OEM Table Load:
[    0.202286] ACPI: SSDT 0xFFFF96E4CCE54800 000119 (v01 PmRef  ApCst    00003000 INTL 20120518)
[    0.204872] ACPI: Interpreter enabled
[    0.204917] ACPI: (supports S0 S3 S4 S5)
[    0.204920] ACPI: Using IOAPIC for interrupt routing
[    0.204953] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.205359] ACPI: Enabled 7 GPEs in block 00 to 7F
[    0.213140] ACPI: Power Resource [PDOC] (off)
[    0.215968] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-3f])
[    0.215978] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
[    0.216118] acpi PNP0A08:00: _OSC failed (AE_ERROR); disabling ASPM
[    0.216744] PCI host bridge to bus 0000:00
[    0.216749] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.216753] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.216756] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.216759] pci_bus 0000:00: root bus resource [mem 0x000c0000-0x000c3fff window]
[    0.216762] pci_bus 0000:00: root bus resource [mem 0x000c4000-0x000c7fff window]
[    0.216766] pci_bus 0000:00: root bus resource [mem 0x000c8000-0x000cbfff window]
[    0.216769] pci_bus 0000:00: root bus resource [mem 0x000cc000-0x000cffff window]
[    0.216772] pci_bus 0000:00: root bus resource [mem 0x000d0000-0x000d3fff window]
[    0.216775] pci_bus 0000:00: root bus resource [mem 0x000d4000-0x000d7fff window]
[    0.216778] pci_bus 0000:00: root bus resource [mem 0x000d8000-0x000dbfff window]
[    0.216781] pci_bus 0000:00: root bus resource [mem 0x000dc000-0x000dffff window]
[    0.216785] pci_bus 0000:00: root bus resource [mem 0xdfa00000-0xfeafffff window]
[    0.216788] pci_bus 0000:00: root bus resource [bus 00-3f]
[    0.216804] pci 0000:00:00.0: [8086:0a04] type 00 class 0x060000
[    0.216982] pci 0000:00:02.0: [8086:0a16] type 00 class 0x030000
[    0.216995] pci 0000:00:02.0: reg 0x10: [mem 0xf0000000-0xf03fffff 64bit]
[    0.217004] pci 0000:00:02.0: reg 0x18: [mem 0xe0000000-0xefffffff 64bit pref]
[    0.217011] pci 0000:00:02.0: reg 0x20: [io  0x3000-0x303f]
[    0.217027] pci 0000:00:02.0: BAR 2: assigned to efifb
[    0.217198] pci 0000:00:03.0: [8086:0a0c] type 00 class 0x040300
[    0.217209] pci 0000:00:03.0: reg 0x10: [mem 0xf0630000-0xf0633fff 64bit]
[    0.217413] pci 0000:00:14.0: [8086:9c31] type 00 class 0x0c0330
[    0.217431] pci 0000:00:14.0: reg 0x10: [mem 0xf0620000-0xf062ffff 64bit]
[    0.217492] pci 0000:00:14.0: PME# supported from D3hot D3cold
[    0.217659] pci 0000:00:16.0: [8086:9c3a] type 00 class 0x078000
[    0.217679] pci 0000:00:16.0: reg 0x10: [mem 0xf0638000-0xf063801f 64bit]
[    0.217749] pci 0000:00:16.0: PME# supported from D0 D3hot D3cold
[    0.217911] pci 0000:00:19.0: [8086:1559] type 00 class 0x020000
[    0.217926] pci 0000:00:19.0: reg 0x10: [mem 0xf0600000-0xf061ffff]
[    0.217935] pci 0000:00:19.0: reg 0x14: [mem 0xf063d000-0xf063dfff]
[    0.217944] pci 0000:00:19.0: reg 0x18: [io  0x3080-0x309f]
[    0.218001] pci 0000:00:19.0: PME# supported from D0 D3hot D3cold
[    0.218171] pci 0000:00:1b.0: [8086:9c20] type 00 class 0x040300
[    0.218187] pci 0000:00:1b.0: reg 0x10: [mem 0xf0634000-0xf0637fff 64bit]
[    0.218250] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
[    0.218416] pci 0000:00:1c.0: [8086:9c1a] type 01 class 0x060400
[    0.218495] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[    0.218751] pci 0000:00:1c.2: [8086:9c14] type 01 class 0x060400
[    0.218838] pci 0000:00:1c.2: PME# supported from D0 D3hot D3cold
[    0.219097] pci 0000:00:1f.0: [8086:9c43] type 00 class 0x060100
[    0.219369] pci 0000:00:1f.2: [8086:9c03] type 00 class 0x010601
[    0.219383] pci 0000:00:1f.2: reg 0x10: [io  0x30a8-0x30af]
[    0.219391] pci 0000:00:1f.2: reg 0x14: [io  0x30b4-0x30b7]
[    0.219399] pci 0000:00:1f.2: reg 0x18: [io  0x30a0-0x30a7]
[    0.219407] pci 0000:00:1f.2: reg 0x1c: [io  0x30b0-0x30b3]
[    0.219415] pci 0000:00:1f.2: reg 0x20: [io  0x3060-0x307f]
[    0.219424] pci 0000:00:1f.2: reg 0x24: [mem 0xf063c000-0xf063c7ff]
[    0.219460] pci 0000:00:1f.2: PME# supported from D3hot
[    0.219690] pci 0000:01:00.0: [10ec:5227] type 00 class 0xff0000
[    0.219710] pci 0000:01:00.0: reg 0x10: [mem 0xf0500000-0xf0500fff]
[    0.219836] pci 0000:01:00.0: supports D1 D2
[    0.219839] pci 0000:01:00.0: PME# supported from D1 D2 D3hot D3cold
[    0.219997] pci 0000:00:1c.0: PCI bridge to [bus 01]
[    0.220004] pci 0000:00:1c.0:   bridge window [mem 0xf0500000-0xf05fffff]
[    0.220243] pci 0000:02:00.0: [8086:08b3] type 00 class 0x028000
[    0.220329] pci 0000:02:00.0: reg 0x10: [mem 0xf0400000-0xf0401fff 64bit]
[    0.220611] pci 0000:02:00.0: PME# supported from D0 D3hot D3cold
[    0.221139] pci 0000:00:1c.2: PCI bridge to [bus 02]
[    0.221145] pci 0000:00:1c.2:   bridge window [mem 0xf0400000-0xf04fffff]
[    0.222127] ACPI: PCI Interrupt Link [LNKA] (IRQs 1 3 4 5 6 7 10 12 14 15) *0, disabled.
[    0.222213] ACPI: PCI Interrupt Link [LNKB] (IRQs 1 3 4 5 6 7 11 12 14 15) *0, disabled.
[    0.222295] ACPI: PCI Interrupt Link [LNKC] (IRQs 1 3 4 5 6 7 10 12 14 15) *0, disabled.
[    0.222375] ACPI: PCI Interrupt Link [LNKD] (IRQs 1 3 4 5 6 7 11 12 14 15) *0, disabled.
[    0.222455] ACPI: PCI Interrupt Link [LNKE] (IRQs 1 3 4 5 6 7 10 12 14 15) *0, disabled.
[    0.222535] ACPI: PCI Interrupt Link [LNKF] (IRQs 1 3 4 5 6 7 11 12 14 15) *0, disabled.
[    0.222615] ACPI: PCI Interrupt Link [LNKG] (IRQs 1 3 4 5 6 7 10 12 14 15) *0, disabled.
[    0.222696] ACPI: PCI Interrupt Link [LNKH] (IRQs 1 3 4 5 6 7 11 12 14 15) *0, disabled.
[    0.223253] iommu: Default domain type: Translated 
[    0.223253] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    0.223253] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    0.223253] pci 0000:00:02.0: vgaarb: bridge control possible
[    0.223253] vgaarb: loaded
[    0.223253] SCSI subsystem initialized
[    0.223253] libata version 3.00 loaded.
[    0.223253] ACPI: bus type USB registered
[    0.223253] usbcore: registered new interface driver usbfs
[    0.223253] usbcore: registered new interface driver hub
[    0.223253] usbcore: registered new device driver usb
[    0.223253] pps_core: LinuxPPS API ver. 1 registered
[    0.223253] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.223253] PTP clock support registered
[    0.223253] EDAC MC: Ver: 3.0.0
[    0.223253] Registered efivars operations
[    0.223253] NetLabel: Initializing
[    0.223253] NetLabel:  domain hash size = 128
[    0.223253] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.223253] NetLabel:  unlabeled traffic allowed by default
[    0.223253] PCI: Using ACPI for IRQ routing
[    0.223372] PCI: pci_cache_line_size set to 64 bytes
[    0.223690] e820: reserve RAM buffer [mem 0x00058000-0x0005ffff]
[    0.223691] e820: reserve RAM buffer [mem 0x00098000-0x0009ffff]
[    0.223692] e820: reserve RAM buffer [mem 0xa1e5a018-0xa3ffffff]
[    0.223693] e820: reserve RAM buffer [mem 0xd7c6c018-0xd7ffffff]
[    0.223694] e820: reserve RAM buffer [mem 0xd912a000-0xdbffffff]
[    0.223695] e820: reserve RAM buffer [mem 0xdbfb9000-0xdbffffff]
[    0.223696] e820: reserve RAM buffer [mem 0xdd000000-0xdfffffff]
[    0.223698] e820: reserve RAM buffer [mem 0x41f600000-0x41fffffff]
[    0.226787] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0
[    0.226795] hpet0: 8 comparators, 64-bit 14.318180 MHz counter
[    0.227046] clocksource: Switched to clocksource tsc-early
[    0.242621] VFS: Disk quotas dquot_6.6.0
[    0.242643] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.242771] AppArmor: AppArmor Filesystem Enabled
[    0.242802] pnp: PnP ACPI init
[    0.243130] system 00:00: [mem 0xfed1c000-0xfed1ffff] has been reserved
[    0.243136] system 00:00: [mem 0xfed10000-0xfed17fff] could not be reserved
[    0.243139] system 00:00: [mem 0xfed18000-0xfed18fff] has been reserved
[    0.243142] system 00:00: [mem 0xfed19000-0xfed19fff] has been reserved
[    0.243146] system 00:00: [mem 0xf8000000-0xfbffffff] has been reserved
[    0.243149] system 00:00: [mem 0xfed20000-0xfed3ffff] has been reserved
[    0.243152] system 00:00: [mem 0xfed40000-0xfed8ffff] has been reserved
[    0.243155] system 00:00: [mem 0xfed90000-0xfed91fff] has been reserved
[    0.243158] system 00:00: [mem 0xff000000-0xffffffff] could not be reserved
[    0.243162] system 00:00: [mem 0xfee00000-0xfeefffff] could not be reserved
[    0.243165] system 00:00: [mem 0xfed80000-0xfed83fff] has been reserved
[    0.243168] system 00:00: [mem 0xfed84000-0xfed87fff] has been reserved
[    0.243171] system 00:00: [mem 0xdfa11000-0xdfa11fff] has been reserved
[    0.243174] system 00:00: [mem 0xdfa20000-0xdfa2ffff] has been reserved
[    0.243183] system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.243860] system 00:01: [mem 0xfed00000-0xfed003ff] has been reserved
[    0.243867] system 00:01: Plug and Play ACPI device, IDs PNP0103 PNP0c01 (active)
[    0.243914] system 00:02: [io  0x2008-0x200b] has been reserved
[    0.243918] system 00:02: [io  0xffff] has been reserved
[    0.243920] system 00:02: [io  0xffff] has been reserved
[    0.243924] system 00:02: [io  0x0800-0x08fe] has been reserved
[    0.243927] system 00:02: [io  0x0900-0x09fe] has been reserved
[    0.243930] system 00:02: [io  0x0a00-0x0afe] has been reserved
[    0.243933] system 00:02: [io  0x0b00-0x0bfe] has been reserved
[    0.243936] system 00:02: [io  0x1800-0x18fe] has been reserved
[    0.243941] system 00:02: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.243985] system 00:03: [io  0x0680-0x06ff] has been reserved
[    0.243988] system 00:03: [io  0x01e0-0x01e7] has been reserved
[    0.243994] system 00:03: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.244020] pnp 00:04: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.244045] pnp 00:05: Plug and Play ACPI device, IDs TOS7407 PNP0303 (active)
[    0.244066] pnp 00:06: Plug and Play ACPI device, IDs TTP1000 PNP0f13 (active)
[    0.244557] pnp: PnP ACPI: found 7 devices
[    0.250471] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.250545] NET: Registered protocol family 2
[    0.250750] tcp_listen_portaddr_hash hash table entries: 8192 (order: 5, 131072 bytes, linear)
[    0.250858] TCP established hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.251091] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[    0.251191] TCP: Hash tables configured (established 131072 bind 65536)
[    0.251247] UDP hash table entries: 8192 (order: 6, 262144 bytes, linear)
[    0.251305] UDP-Lite hash table entries: 8192 (order: 6, 262144 bytes, linear)
[    0.251382] NET: Registered protocol family 1
[    0.251391] NET: Registered protocol family 44
[    0.251403] pci 0000:00:1c.0: PCI bridge to [bus 01]
[    0.251411] pci 0000:00:1c.0:   bridge window [mem 0xf0500000-0xf05fffff]
[    0.251419] pci 0000:00:1c.2: PCI bridge to [bus 02]
[    0.251425] pci 0000:00:1c.2:   bridge window [mem 0xf0400000-0xf04fffff]
[    0.251435] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.251438] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.251441] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.251444] pci_bus 0000:00: resource 7 [mem 0x000c0000-0x000c3fff window]
[    0.251447] pci_bus 0000:00: resource 8 [mem 0x000c4000-0x000c7fff window]
[    0.251450] pci_bus 0000:00: resource 9 [mem 0x000c8000-0x000cbfff window]
[    0.251453] pci_bus 0000:00: resource 10 [mem 0x000cc000-0x000cffff window]
[    0.251456] pci_bus 0000:00: resource 11 [mem 0x000d0000-0x000d3fff window]
[    0.251459] pci_bus 0000:00: resource 12 [mem 0x000d4000-0x000d7fff window]
[    0.251462] pci_bus 0000:00: resource 13 [mem 0x000d8000-0x000dbfff window]
[    0.251465] pci_bus 0000:00: resource 14 [mem 0x000dc000-0x000dffff window]
[    0.251468] pci_bus 0000:00: resource 15 [mem 0xdfa00000-0xfeafffff window]
[    0.251471] pci_bus 0000:01: resource 1 [mem 0xf0500000-0xf05fffff]
[    0.251474] pci_bus 0000:02: resource 1 [mem 0xf0400000-0xf04fffff]
[    0.251597] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.252369] PCI: CLS 64 bytes, default 64
[    0.252412] Trying to unpack rootfs image as initramfs...
[    0.485690] Initramfs unpacking failed: Decoding failed
[    0.495887] Freeing initrd memory: 81296K
[    0.495959] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    0.495967] software IO TLB: mapped [mem 0xd3c69000-0xd7c69000] (64MB)
[    0.496066] resource sanity check: requesting [mem 0xfed10000-0xfed15fff], which spans more than Reserved [mem 0xfed10000-0xfed13fff]
[    0.496076] caller snb_uncore_imc_init_box+0x7a/0xc0 mapping multiple BARs
[    0.496200] check: Scanning for low memory corruption every 60 seconds
[    0.496718] Initialise system trusted keyrings
[    0.496732] Key type blacklist registered
[    0.496770] workingset: timestamp_bits=36 max_order=22 bucket_order=0
[    0.498199] zbud: loaded
[    0.498505] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.498680] fuse: init (API version 7.31)
[    0.498837] integrity: Platform Keyring initialized
[    0.514065] Key type asymmetric registered
[    0.514069] Asymmetric key parser 'x509' registered
[    0.514080] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
[    0.514130] io scheduler mq-deadline registered
[    0.515055] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    0.515119] efifb: probing for efifb
[    0.515143] efifb: framebuffer at 0xe0000000, using 8100k, total 8100k
[    0.515146] efifb: mode is 1920x1080x32, linelength=7680, pages=1
[    0.515148] efifb: scrolling: redraw
[    0.515151] efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
[    0.515250] Console: switching to colour frame buffer device 240x67
[    0.521077] fb0: EFI VGA frame buffer device
[    0.521105] intel_idle: MWAIT substates: 0x11142120
[    0.521106] intel_idle: v0.5.1 model 0x45
[    0.521410] intel_idle: Local APIC timer is reliable in all C-states
[    0.521604] ACPI: AC Adapter [ADP1] (on-line)
[    0.521697] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
[    0.521771] ACPI: Power Button [PWRB]
[    0.521825] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input1
[    0.521881] ACPI: Lid Switch [LID]
[    0.521932] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
[    0.521986] ACPI: Power Button [PWRF]
[    0.522698] thermal LNXTHERM:00: registered as thermal_zone0
[    0.522728] ACPI: Thermal Zone [TZ01] (16 C)
[    0.522965] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[    0.523933] battery: ACPI: Battery Slot [BAT1] (battery present)
[    0.525307] Linux agpgart interface v0.103
[    0.527964] loop: module loaded
[    0.528260] libphy: Fixed MDIO Bus: probed
[    0.528283] tun: Universal TUN/TAP device driver, 1.6
[    0.528350] PPP generic driver version 2.4.2
[    0.528441] VFIO - User Level meta-driver version: 0.3
[    0.528552] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.528585] ehci-pci: EHCI PCI platform driver
[    0.528619] ehci-platform: EHCI generic platform driver
[    0.528654] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.528684] ohci-pci: OHCI PCI platform driver
[    0.528710] ohci-platform: OHCI generic platform driver
[    0.528742] uhci_hcd: USB Universal Host Controller Interface driver
[    0.529145] xhci_hcd 0000:00:14.0: xHCI Host Controller
[    0.529178] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1
[    0.530258] xhci_hcd 0000:00:14.0: hcc params 0x200077c1 hci version 0x100 quirks 0x000000000004b810
[    0.530306] xhci_hcd 0000:00:14.0: cache line size of 64 is not supported
[    0.530492] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.08
[    0.530532] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.530567] usb usb1: Product: xHCI Host Controller
[    0.530592] usb usb1: Manufacturer: Linux 5.8.0+ xhci-hcd
[    0.530618] usb usb1: SerialNumber: 0000:00:14.0
[    0.530769] hub 1-0:1.0: USB hub found
[    0.530799] hub 1-0:1.0: 9 ports detected
[    0.531435] xhci_hcd 0000:00:14.0: xHCI Host Controller
[    0.531465] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2
[    0.531502] xhci_hcd 0000:00:14.0: Host supports USB 3.0 SuperSpeed
[    0.531569] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.08
[    0.531608] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.531643] usb usb2: Product: xHCI Host Controller
[    0.533083] usb usb2: Manufacturer: Linux 5.8.0+ xhci-hcd
[    0.534527] usb usb2: SerialNumber: 0000:00:14.0
[    0.536074] hub 2-0:1.0: USB hub found
[    0.537481] hub 2-0:1.0: 4 ports detected
[    0.539223] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12
[    0.542306] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.543775] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.545303] mousedev: PS/2 mouse device common for all mice
[    0.546949] rtc_cmos 00:04: RTC can wake from S4
[    0.548659] rtc_cmos 00:04: registered as rtc0
[    0.550089] rtc_cmos 00:04: setting system clock to 2020-08-12T08:22:01 UTC (1597220521)
[    0.551639] rtc_cmos 00:04: alarms up to one year, 242 bytes nvram, hpet irqs
[    0.553161] i2c /dev entries driver
[    0.554727] device-mapper: uevent: version 1.0.3
[    0.556320] device-mapper: ioctl: 4.42.0-ioctl (2020-02-27) initialised: dm-devel@redhat.com
[    0.557879] platform eisa.0: Probing EISA bus 0
[    0.559420] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3
[    0.560863] platform eisa.0: EISA: Cannot allocate resource for mainboard
[    0.562425] platform eisa.0: Cannot allocate resource for EISA slot 1
[    0.563840] platform eisa.0: Cannot allocate resource for EISA slot 2
[    0.565250] platform eisa.0: Cannot allocate resource for EISA slot 3
[    0.566675] platform eisa.0: Cannot allocate resource for EISA slot 4
[    0.568050] platform eisa.0: Cannot allocate resource for EISA slot 5
[    0.569404] platform eisa.0: Cannot allocate resource for EISA slot 6
[    0.570753] platform eisa.0: Cannot allocate resource for EISA slot 7
[    0.572071] platform eisa.0: Cannot allocate resource for EISA slot 8
[    0.573376] platform eisa.0: EISA: Detected 0 cards
[    0.574674] intel_pstate: Intel P-state driver initializing
[    0.576328] ledtrig-cpu: registered to indicate activity on CPUs
[    0.577381] EFI Variables Facility v0.08 2004-May-17
[    0.640508] drop_monitor: Initializing network drop monitor service
[    0.641412] NET: Registered protocol family 10
[    0.648329] Segment Routing with IPv6
[    0.649258] NET: Registered protocol family 17
[    0.650231] Key type dns_resolver registered
[    0.651331] microcode: sig=0x40651, pf=0x40, revision=0x26
[    0.652302] microcode: Microcode Update Driver: v2.2.
[    0.652306] IPI shorthand broadcast: enabled
[    0.653857] sched_clock: Marking stable (651561387, 737561)->(674811703, -22512755)
[    0.654777] registered taskstats version 1
[    0.655553] Loading compiled-in X.509 certificates
[    0.657098] Loaded X.509 cert 'Build time autogenerated kernel key: 0e9ed617171c32d07f092c49ca26dcd8bccca9c8'
[    0.658092] zswap: loaded using pool lzo/zbud
[    0.659112] Key type ._fscrypt registered
[    0.660043] Key type .fscrypt registered
[    0.660968] Key type fscrypt-provisioning registered
[    0.664553] Key type encrypted registered
[    0.665339] AppArmor: AppArmor sha1 policy hashing enabled
[    0.668322] integrity: Loading X.509 certificate: UEFI:db
[    0.669131] integrity: Loaded X.509 cert 'Microsoft Windows Production PCA 2011: a92902398e16c49778cd90f99e4f9ae17c55af53'
[    0.669928] integrity: Loading X.509 certificate: UEFI:db
[    0.670772] integrity: Loaded X.509 cert 'Microsoft Corporation UEFI CA 2011: 13adbf4309bd82709c8cd54f316ed522988a1bd4'
[    0.671582] integrity: Loading X.509 certificate: UEFI:db
[    0.672943] integrity: Loaded X.509 cert 'Toshiba Corporation Utility CA 2012: cc37e82804de6d8242718850b1af8d5f'
[    0.673922] integrity: Loading X.509 certificate: UEFI:db
[    0.675022] integrity: Loaded X.509 cert 'Toshiba Corporation Utility CA2 2012: a1064208cec529a74554a21ede898879'
[    0.678678] ima: No TPM chip found, activating TPM-bypass!
[    0.679709] ima: Allocated hash algorithm: sha1
[    0.680546] ima: No architecture policies found
[    0.681385] evm: Initialising EVM extended attributes:
[    0.682264] evm: security.selinux
[    0.683100] evm: security.SMACK64
[    0.683926] evm: security.SMACK64EXEC
[    0.684744] evm: security.SMACK64TRANSMUTE
[    0.685549] evm: security.SMACK64MMAP
[    0.686375] evm: security.apparmor
[    0.687161] evm: security.ima
[    0.687940] evm: security.capability
[    0.688715] evm: HMAC attrs: 0x1
[    0.689857] PM:   Magic number: 0:583:371
[    0.690832] RAS: Correctable Errors collector initialized.
[    0.692558] Freeing unused decrypted memory: 2040K
[    0.693704] Freeing unused kernel image (initmem) memory: 2596K
[    0.718090] Write protecting the kernel read-only data: 22528k
[    0.720339] Freeing unused kernel image (text/rodata gap) memory: 2044K
[    0.721271] Freeing unused kernel image (rodata/data gap) memory: 852K
[    0.766153] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    0.766932] x86/mm: Checking user space page tables
[    0.809888] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    0.810688] Run /init as init process
[    0.811459]   with arguments:
[    0.811459]     /init
[    0.811460]   with environment:
[    0.811460]     HOME=/
[    0.811460]     TERM=linux
[    0.811461]     BOOT_IMAGE=/vmlinuz-5.8.0+
[    0.878039] usb 1-5: new high-speed USB device number 2 using xhci_hcd
[    0.907017] e1000e: Intel(R) PRO/1000 Network Driver
[    0.908864] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[    0.911125] e1000e 0000:00:19.0: Interrupt Throttling Rate (ints/sec) set to dynamic conservative mode
[    0.913486] ahci 0000:00:1f.2: version 3.0
[    0.913914] ahci 0000:00:1f.2: SSS flag set, parallel bus scan disabled
[    0.926048] ahci 0000:00:1f.2: AHCI 0001.0300 32 slots 3 ports 6 Gbps 0x1 impl SATA mode
[    0.927574] ahci 0000:00:1f.2: flags: 64bit ncq stag pm led clo only pio slum part deso sadm sds apst 
[    0.930309] scsi host0: ahci
[    0.932556] scsi host1: ahci
[    0.939700] cryptd: max_cpu_qlen set to 1000
[    0.946686] scsi host2: ahci
[    0.947874] ata1: SATA max UDMA/133 abar m2048@0xf063c000 port 0xf063c100 irq 44
[    0.948985] ata2: DUMMY
[    0.949979] ata3: DUMMY
[    0.952161] AVX2 version of gcm_enc/dec engaged.
[    0.953146] AES CTR mode by8 optimization enabled
[    0.956142] rtsx_pci 0000:01:00.0: enabling device (0000 -> 0002)
[    1.016480] checking generic (e0000000 7e9000) vs hw (f0000000 400000)
[    1.016482] checking generic (e0000000 7e9000) vs hw (e0000000 10000000)
[    1.016483] fb0: switching to inteldrmfb from EFI VGA
[    1.017736] Console: switching to colour dummy device 80x25
[    1.017788] i915 0000:00:02.0: vgaarb: deactivate vga console
[    1.031485] usb 1-5: New USB device found, idVendor=2109, idProduct=2812, bcdDevice= b.e0
[    1.031491] usb 1-5: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    1.031494] usb 1-5: Product: USB2.0 Hub             
[    1.031496] usb 1-5: Manufacturer: VIA Labs, Inc.         
[    1.032079] hub 1-5:1.0: USB hub found
[    1.032223] hub 1-5:1.0: 4 ports detected
[    1.034050] i915 0000:00:02.0: vgaarb: changed VGA decodes: olddecodes=io+mem,decodes=io+mem:owns=io+mem
[    1.073822] [drm] Initialized i915 1.6.0 20200715 for 0000:00:02.0 on minor 0
[    1.074781] ACPI: Video Device [GFX0] (multi-head: yes  rom: yes  post: no)
[    1.075123] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:01/input/input6
[    1.106270] fbcon: i915drmfb (fb0) is primary device
[    1.158268] usb 2-1: new SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[    1.184290] usb 2-1: New USB device found, idVendor=090c, idProduct=1000, bcdDevice=11.00
[    1.184293] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    1.184295] usb 2-1: Product: Flash Drive FIT
[    1.184296] usb 2-1: Manufacturer: Samsung
[    1.184298] usb 2-1: SerialNumber: 0334617100009558
[    1.187701] e1000e 0000:00:19.0 0000:00:19.0 (uninitialized): registered PHC clock
[    1.256459] e1000e 0000:00:19.0 eth0: (PCI Express:2.5GT/s:Width x1) b8:6b:23:c2:23:86
[    1.256461] e1000e 0000:00:19.0 eth0: Intel(R) PRO/1000 Network Connection
[    1.256504] e1000e 0000:00:19.0 eth0: MAC: 11, PHY: 12, PBA No: FFFFFF-0FF
[    1.257626] e1000e 0000:00:19.0 enp0s25: renamed from eth0
[    1.268852] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[    1.270967] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
[    1.271072] ata1.00: ACPI cmd ef/10:09:00:00:00:a0 (SET FEATURES) succeeded
[    1.271363] ata1.00: supports DRM functions and may not be fully accessible
[    1.276386] ata1.00: disabling queued TRIM support
[    1.276387] ata1.00: ATA-9: Samsung SSD 850 EVO mSATA 1TB, EMT41B6Q, max UDMA/133
[    1.276398] ata1.00: 1953525168 sectors, multi 1: LBA48 NCQ (depth 32), AA
[    1.281944] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
[    1.281975] ata1.00: ACPI cmd ef/10:09:00:00:00:a0 (SET FEATURES) succeeded
[    1.282198] ata1.00: supports DRM functions and may not be fully accessible
[    1.287063] ata1.00: disabling queued TRIM support
[    1.292218] ata1.00: configured for UDMA/133
[    1.302552] scsi 0:0:0:0: Direct-Access     ATA      Samsung SSD 850  1B6Q PQ: 0 ANSI: 5
[    1.302739] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    1.302858] sd 0:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB)
[    1.302872] sd 0:0:0:0: [sda] Write Protect is off
[    1.302874] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    1.302894] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    1.306107] usb 1-6: new high-speed USB device number 3 using xhci_hcd
[    1.326884] sd 0:0:0:0: [sda] supports TCG Opal
[    1.326887] sd 0:0:0:0: [sda] Attached SCSI disk
[    1.457120] usb 1-6: New USB device found, idVendor=05e3, idProduct=0608, bcdDevice=88.30
[    1.457122] usb 1-6: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.457123] usb 1-6: Product: USB2.0 Hub
[    1.458307] hub 1-6:1.0: USB hub found
[    1.458727] hub 1-6:1.0: 4 ports detected
[    1.502129] tsc: Refined TSC clocksource calibration: 2593.992 MHz
[    1.502140] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x25641074d3b, max_idle_ns: 440795244898 ns
[    1.502169] clocksource: Switched to clocksource tsc
[    1.582229] usb 2-2: new SuperSpeed Gen 1 USB device number 3 using xhci_hcd
[    1.603223] usb 2-2: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
[    1.603225] usb 2-2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    1.603227] usb 2-2: Product: Ugreen Storage Device
[    1.603228] usb 2-2: Manufacturer: Ugreen
[    1.603229] usb 2-2: SerialNumber: 00000000DEF0
[    1.694113] usb 1-5.2: new full-speed USB device number 4 using xhci_hcd
[    1.808210] usb 1-5.2: New USB device found, idVendor=1038, idProduct=1361, bcdDevice= 1.03
[    1.808212] usb 1-5.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    1.808213] usb 1-5.2: Product: SteelSeries Sensei
[    1.808214] usb 1-5.2: Manufacturer: La-VIEW Technology
[    1.934413] usb 2-4: new SuperSpeed Gen 1 USB device number 4 using xhci_hcd
[    2.186981] usb 2-4: New USB device found, idVendor=2109, idProduct=0812, bcdDevice= b.e1
[    2.186983] usb 2-4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.186985] usb 2-4: Product: USB3.0 Hub             
[    2.187054] usb 2-4: Manufacturer: VIA Labs, Inc.         
[    2.189292] hub 2-4:1.0: USB hub found
[    2.189425] hub 2-4:1.0: 4 ports detected
[    2.196275] usb-storage 2-1:1.0: USB Mass Storage device detected
[    2.196380] usb-storage 2-1:1.0: Quirks match for vid 090c pid 1000: 400
[    2.196408] scsi host3: usb-storage 2-1:1.0
[    2.196494] usb 2-2: UAS is ignored for this device, using usb-storage instead
[    2.196495] usb-storage 2-2:1.0: USB Mass Storage device detected
[    2.196558] usb-storage 2-2:1.0: Quirks match for vid 174c pid 55aa: c00000
[    2.196580] scsi host4: usb-storage 2-2:1.0
[    2.196643] usbcore: registered new interface driver usb-storage
[    2.197817] usbcore: registered new interface driver uas
[    2.253627] input: AlpsPS/2 ALPS GlidePoint as /devices/platform/i8042/serio1/input/input5
[    2.282110] usb 1-5.4: new high-speed USB device number 5 using xhci_hcd
[    2.340786] Console: switching to colour frame buffer device 240x67
[    2.383565] i915 0000:00:02.0: [drm] fb0: i915drmfb frame buffer device
[    2.397215] usb 1-5.4: New USB device found, idVendor=2109, idProduct=2812, bcdDevice= b.e0
[    2.398590] usb 1-5.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.399564] usb 1-5.4: Product: USB2.0 Hub             
[    2.400533] usb 1-5.4: Manufacturer: VIA Labs, Inc.         
[    2.402310] hub 1-5.4:1.0: USB hub found
[    2.403889] hub 1-5.4:1.0: 4 ports detected
[    2.421261] hid: raw HID events driver (C) Jiri Kosina
[    2.426824] usbcore: registered new interface driver usbhid
[    2.428259] usbhid: USB HID core driver
[    2.431423] input: La-VIEW Technology SteelSeries Sensei as /devices/pci0000:00/0000:00:14.0/usb1/1-5/1-5.2/1-5.2:1.0/0003:1038:1361.0001/input/input7
[    2.432724] hid-generic 0003:1038:1361.0001: input,hidraw0: USB HID v1.00 Mouse [La-VIEW Technology SteelSeries Sensei] on usb-0000:00:14.0-5.2/input0
[    2.434139] input: La-VIEW Technology SteelSeries Sensei Keyboard as /devices/pci0000:00/0000:00:14.0/usb1/1-5/1-5.2/1-5.2:1.1/0003:1038:1361.0002/input/input8
[    2.435307] hid-generic 0003:1038:1361.0002: input,hiddev0,hidraw1: USB HID v1.00 Keyboard [La-VIEW Technology SteelSeries Sensei] on usb-0000:00:14.0-5.2/input1
[    2.578120] usb 2-4.4: new SuperSpeed Gen 1 USB device number 5 using xhci_hcd
[    2.834805] usb 2-4.4: New USB device found, idVendor=2109, idProduct=0812, bcdDevice= b.e1
[    2.836431] usb 2-4.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.837508] usb 2-4.4: Product: USB3.0 Hub             
[    2.838612] usb 2-4.4: Manufacturer: VIA Labs, Inc.         
[    2.842042] hub 2-4.4:1.0: USB hub found
[    2.843833] hub 2-4.4:1.0: 4 ports detected
[    2.926116] usb 1-5.4.4: new high-speed USB device number 6 using xhci_hcd
[    3.041091] usb 1-5.4.4: New USB device found, idVendor=2109, idProduct=2812, bcdDevice= b.e0
[    3.042611] usb 1-5.4.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    3.043667] usb 1-5.4.4: Product: USB2.0 Hub             
[    3.044724] usb 1-5.4.4: Manufacturer: VIA Labs, Inc.         
[    3.047023] hub 1-5.4.4:1.0: USB hub found
[    3.048742] hub 1-5.4.4:1.0: 4 ports detected
[    3.198636] scsi 4:0:0:0: Direct-Access     Samsung  SSD 860 QVO 4TB  0    PQ: 0 ANSI: 6
[    3.200496] sd 4:0:0:0: Attached scsi generic sg1 type 0
[    3.201919] sd 4:0:0:0: [sdb] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
[    3.203408] sd 4:0:0:0: [sdb] Write Protect is off
[    3.204440] sd 4:0:0:0: [sdb] Mode Sense: 43 00 00 00
[    3.204623] sd 4:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.231914]  sdb: AHDI sdb1 sdb2 sdb3
[    3.234299] sd 4:0:0:0: [sdb] Attached SCSI disk
[    3.238180] usb 2-4.4.4: new SuperSpeed Gen 1 USB device number 6 using xhci_hcd
[    3.360721] scsi 3:0:0:0: Direct-Access     Samsung  Flash Drive FIT  1100 PQ: 0 ANSI: 6
[    3.362653] sd 3:0:0:0: Attached scsi generic sg2 type 0
[    3.363163] sd 3:0:0:0: [sdc] 62668800 512-byte logical blocks: (32.1 GB/29.9 GiB)
[    3.366312] sd 3:0:0:0: [sdc] Write Protect is off
[    3.367711] sd 3:0:0:0: [sdc] Mode Sense: 43 00 00 00
[    3.368178] sd 3:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.394304] GPT:Primary header thinks Alt. header is not at the end of the disk.
[    3.395942] GPT:8388607 != 62668799
[    3.397248] GPT:Alternate GPT header not at the end of the disk.
[    3.398529] GPT:8388607 != 62668799
[    3.399609] GPT: Use GNU Parted to correct GPT errors.
[    3.400688]  sdc: sdc1 sdc2
[    3.403753] sd 3:0:0:0: [sdc] Attached SCSI removable disk
[    3.494363] usb 2-4.4.4: New USB device found, idVendor=2109, idProduct=0812, bcdDevice= b.e1
[    3.495983] usb 2-4.4.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    3.497183] usb 2-4.4.4: Product: USB3.0 Hub             
[    3.498343] usb 2-4.4.4: Manufacturer: VIA Labs, Inc.         
[    3.501464] hub 2-4.4.4:1.0: USB hub found
[    3.503172] hub 2-4.4.4:1.0: 4 ports detected
[    3.586111] usb 1-5.4.4.4: new high-speed USB device number 7 using xhci_hcd
[    3.701347] usb 1-5.4.4.4: New USB device found, idVendor=2109, idProduct=2812, bcdDevice= b.e0
[    3.703017] usb 1-5.4.4.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    3.704099] usb 1-5.4.4.4: Product: USB2.0 Hub             
[    3.705172] usb 1-5.4.4.4: Manufacturer: VIA Labs, Inc.         
[    3.707386] hub 1-5.4.4.4:1.0: USB hub found
[    3.708970] hub 1-5.4.4.4:1.0: 4 ports detected
[    3.890477] usb 2-4.4.4.3: new SuperSpeed Gen 1 USB device number 7 using xhci_hcd
[    3.917024] usb 2-4.4.4.3: New USB device found, idVendor=2001, idProduct=4a00, bcdDevice= 1.00
[    3.918571] usb 2-4.4.4.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.919738] usb 2-4.4.4.3: Product: D-Link DUB-1312
[    3.920797] usb 2-4.4.4.3: Manufacturer: D-Link Elec. Corp.
[    3.921859] usb 2-4.4.4.3: SerialNumber: 000000000005D6
[    4.006198] usb 2-4.4.4.4: new SuperSpeed Gen 1 USB device number 8 using xhci_hcd
[    4.258428] usb 2-4.4.4.4: New USB device found, idVendor=2109, idProduct=0812, bcdDevice= b.e1
[    4.260041] usb 2-4.4.4.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    4.261312] usb 2-4.4.4.4: Product: USB3.0 Hub             
[    4.262352] usb 2-4.4.4.4: Manufacturer: VIA Labs, Inc.         
[    4.265806] hub 2-4.4.4.4:1.0: USB hub found
[    4.267466] hub 2-4.4.4.4:1.0: 4 ports detected
[    4.658215] usb 2-4.4.4.4.2: new SuperSpeed Gen 1 USB device number 9 using xhci_hcd
[    4.682823] usb 2-4.4.4.4.2: New USB device found, idVendor=0480, idProduct=0900, bcdDevice= 3.15
[    4.684307] usb 2-4.4.4.4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    4.685259] usb 2-4.4.4.4.2: Product: External USB 3.0
[    4.686196] usb 2-4.4.4.4.2: Manufacturer: TOSHIBA
[    4.687120] usb 2-4.4.4.4.2: SerialNumber: 20190116020136F
[    4.690116] usb-storage 2-4.4.4.4.2:1.0: USB Mass Storage device detected
[    4.691800] scsi host5: usb-storage 2-4.4.4.4.2:1.0
[    5.696667] scsi 5:0:0:0: Direct-Access     TOSHIBA  External USB 3.0 5438 PQ: 0 ANSI: 6
[    5.698396] sd 5:0:0:0: Attached scsi generic sg3 type 0
[    5.699821] sd 5:0:0:0: [sdd] Very big device. Trying to use READ CAPACITY(16).
[    5.701192] sd 5:0:0:0: [sdd] 7814037164 512-byte logical blocks: (4.00 TB/3.64 TiB)
[    5.702325] sd 5:0:0:0: [sdd] 4096-byte physical blocks
[    5.703730] sd 5:0:0:0: [sdd] Write Protect is off
[    5.704854] sd 5:0:0:0: [sdd] Mode Sense: 23 00 00 00
[    5.705194] sd 5:0:0:0: [sdd] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   11.857932]  sdd: sdd1
[   11.872488] sd 5:0:0:0: [sdd] Attached SCSI disk
[  111.040190] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null)
[  113.124123] systemd[1]: Inserted module 'autofs4'
[  113.401727] systemd[1]: systemd 245.4-4ubuntu3.1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=hybrid)
[  113.422234] systemd[1]: Detected architecture x86-64.
[  113.487195] systemd[1]: Set hostname to <kgxup-fb87d396>.
[  115.647353] systemd[1]: /lib/systemd/system/dbus.socket:5: ListenStream= references a path below legacy directory /var/run/, updating /var/run/dbus/system_bus_socket → /run/dbus/system_bus_socket; please update the unit file accordingly.
[  116.483499] systemd[1]: Created slice system-modprobe.slice.
[  116.486724] systemd[1]: Created slice Cryptsetup Units Slice.
[  116.489791] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[  116.492958] systemd[1]: Created slice system-tor.slice.
[  116.496068] systemd[1]: Created slice User and Session Slice.
[  116.499040] systemd[1]: Started ntp-systemd-netif.path.
[  116.502039] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  116.505135] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[  116.508220] systemd[1]: Reached target User and Group Name Lookups.
[  116.511268] systemd[1]: Reached target Slices.
[  116.514378] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[  116.517486] systemd[1]: Listening on LVM2 poll daemon socket.
[  116.524295] systemd[1]: Listening on RPCbind Server Activation Socket.
[  116.528039] systemd[1]: Listening on Syslog Socket.
[  116.531207] systemd[1]: Listening on fsck to fsckd communication Socket.
[  116.534372] systemd[1]: Listening on initctl Compatibility Named Pipe.
[  116.537622] systemd[1]: Listening on Journal Audit Socket.
[  116.540825] systemd[1]: Listening on Journal Socket (/dev/log).
[  116.544039] systemd[1]: Listening on Journal Socket.
[  116.547205] systemd[1]: Listening on udev Control Socket.
[  116.550362] systemd[1]: Listening on udev Kernel Socket.
[  116.554067] systemd[1]: Mounting Huge Pages File System...
[  116.557877] systemd[1]: Mounting POSIX Message Queue File System...
[  116.561637] systemd[1]: Mounting RPC Pipe File System...
[  116.565595] systemd[1]: Mounting Kernel Debug File System...
[  116.569386] systemd[1]: Mounting Kernel Trace File System...
[  116.573566] systemd[1]: Starting Journal Service...
[  116.578046] systemd[1]: Starting Availability of block devices...
[  116.583821] systemd[1]: Starting Set the console keyboard layout...
[  116.588449] systemd[1]: Starting Create list of static device nodes for the current kernel...
[  116.592930] systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
[  116.596651] systemd[1]: Condition check resulted in Load Kernel Module drm being skipped.
[  116.598976] systemd[1]: Started Nameserver information manager.
[  116.642016] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[  116.643469] systemd[1]: Condition check resulted in File System Check on Root Device being skipped.
[  116.789161] systemd[1]: Starting Load Kernel Modules...
[  116.793493] systemd[1]: Starting Remount Root and Kernel File Systems...
[  116.797808] systemd[1]: Starting udev Coldplug all Devices...
[  116.802151] systemd[1]: Starting Uncomplicated firewall...
[  116.807314] systemd[1]: Mounted Huge Pages File System.
[  116.810666] systemd[1]: Mounted POSIX Message Queue File System.
[  116.813966] systemd[1]: Mounted Kernel Debug File System.
[  116.817269] systemd[1]: Mounted Kernel Trace File System.
[  116.820825] systemd[1]: Finished Availability of block devices.
[  116.824459] systemd[1]: Finished Create list of static device nodes for the current kernel.
[  116.875557] systemd[1]: Finished Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling.
[  116.973300] EXT4-fs (dm-0): re-mounted. Opts: errors=remount-ro
[  116.976197] systemd[1]: Finished Remount Root and Kernel File Systems.
[  116.979759] systemd[1]: Activating swap /swapfile...
[  117.165854] systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
[  117.167778] systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped.
[  117.169786] systemd[1]: Starting Load/Save Random Seed...
[  117.173260] systemd[1]: Starting Create System Users...
[  117.407655] systemd[1]: Finished Uncomplicated firewall.
[  117.463501] RPC: Registered named UNIX socket transport module.
[  117.464659] RPC: Registered udp transport module.
[  117.465803] RPC: Registered tcp transport module.
[  117.466971] RPC: Registered tcp NFSv4.1 backchannel transport module.
[  117.469149] systemd[1]: Mounted RPC Pipe File System.
[  117.578837] systemd[1]: Finished Set the console keyboard layout.
[  117.582800] systemd[1]: Finished udev Coldplug all Devices.
[  117.637985] systemd[1]: Starting Helper to synchronize boot up for ifupdown...
[  117.640956] systemd[1]: Started Journal Service.
[  117.844642] systemd-journald[399]: Received client request to flush runtime journal.
[  117.942408] lp: driver loaded but no devices found
[  118.136459] ppdev: user-space parallel port driver
[  121.510110] Adding 67108860k swap on /swapfile.  Priority:-2 extents:45 across:71663612k FS
[  125.853366] pstore: Using crash dump compression: deflate
[  125.853374] pstore: Registered efi as persistent store backend
[  125.952778] toshiba_bluetooth: Toshiba ACPI Bluetooth device driver
[  126.274042] usb 1-8: new full-speed USB device number 8 using xhci_hcd
[  126.422962] usb 1-8: New USB device found, idVendor=8087, idProduct=07dc, bcdDevice= 0.01
[  126.422965] usb 1-8: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[  126.947897] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[  126.948220] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[  127.091681] mei_me 0000:00:16.0: NFC MEI VERSION: IVN 0x1 Vendor ID 0x1 Type 0x1
[  127.145723] toshiba_acpi: Toshiba Laptop ACPI Extras version 0.24
[  127.147271] toshiba_acpi: Unable to query Hotkey Event Type
[  127.147315] input: Toshiba input device as /devices/virtual/input/input9
[  127.207246] RAPL PMU: API unit is 2^-32 Joules, 4 fixed counters, 655360 ms ovfl timer
[  127.207247] RAPL PMU: hw unit of domain pp0-core 2^-14 Joules
[  127.207248] RAPL PMU: hw unit of domain package 2^-14 Joules
[  127.207249] RAPL PMU: hw unit of domain dram 2^-14 Joules
[  127.207249] RAPL PMU: hw unit of domain pp1-gpu 2^-14 Joules
[  127.275108] toshiba_acpi: Supported laptop features: hotkeys fan keyboard-backlight touchpad usb-sleep-charge usb-rapid-charge panel-power-on usb3 cooling-method
[  127.520209] usb 1-8: USB disconnect, device number 8
[  127.804182] Intel(R) Wireless WiFi driver for Linux
[  127.804407] iwlwifi 0000:02:00.0: enabling device (0000 -> 0002)
[  127.869636] nfc: nfc_init: NFC Core ver 0.1
[  127.869655] NET: Registered protocol family 39
[  128.321244] iwlwifi 0000:02:00.0: loaded firmware version 17.3216344376.0 3160-17.ucode op_mode iwlmvm
[  128.332105] Bluetooth: Core ver 2.22
[  128.332133] NET: Registered protocol family 31
[  128.332134] Bluetooth: HCI device and connection manager initialized
[  128.332138] Bluetooth: HCI socket layer initialized
[  128.332140] Bluetooth: L2CAP socket layer initialized
[  128.332143] Bluetooth: SCO socket layer initialized
[  128.359503] Probing NFC pn544
[  128.890266] usbcore: registered new interface driver btusb
[  128.942606] ax88179_178a 2-4.4.4.3:1.0 eth0: register 'ax88179_178a' at usb-0000:00:14.0-4.4.4.3, D-Link DUB-1312 USB 3.0 to Gigabit Ethernet Adapter, 90:8d:78:04:33:27
[  128.943392] usbcore: registered new interface driver ax88179_178a
[  129.047476] ax88179_178a 2-4.4.4.3:1.0 enx908d78043327: renamed from eth0
[  129.151191] iwlwifi 0000:02:00.0: Detected Intel(R) Dual Band Wireless AC 3160, REV=0x164
[  129.174104] iwlwifi 0000:02:00.0: base HW address: 34:de:1a:3e:4f:e5
[  129.244238] snd_hda_intel 0000:00:03.0: enabling device (0000 -> 0002)
[  129.244740] snd_hda_intel 0000:00:03.0: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915])
[  129.249206] intel_rapl_common: Found RAPL domain package
[  129.249207] intel_rapl_common: Found RAPL domain core
[  129.249208] intel_rapl_common: Found RAPL domain uncore
[  129.249209] intel_rapl_common: Found RAPL domain dram
[  129.253311] snd_hda_intel 0000:00:1b.0: enabling device (0000 -> 0002)
[  129.293231] EXT4-fs (sdc1): mounting ext2 file system using the ext4 subsystem
[  129.296048] ieee80211 phy0: Selected rate control algorithm 'iwl-mvm-rs'
[  129.303819] EXT4-fs (sdc1): mounted filesystem without journal. Opts: (null)
[  129.303826] ext2 filesystem being mounted at /boot supports timestamps until 2038 (0x7fffffff)
[  129.365293] iwlwifi 0000:02:00.0 wlp2s0: renamed from wlan0
[  129.412232] input: HDA Intel HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:03.0/sound/card0/input10
[  129.412319] input: HDA Intel HDMI HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:03.0/sound/card0/input11
[  129.412397] input: HDA Intel HDMI HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:03.0/sound/card0/input12
[  129.412467] input: HDA Intel HDMI HDMI/DP,pcm=9 as /devices/pci0000:00/0000:00:03.0/sound/card0/input13
[  129.412541] input: HDA Intel HDMI HDMI/DP,pcm=10 as /devices/pci0000:00/0000:00:03.0/sound/card0/input14
[  129.684060] snd_hda_codec_realtek hdaudioC1D0: autoconfig for ALC283: line_outs=1 (0x14/0x0/0x0/0x0/0x0) type:speaker
[  129.684063] snd_hda_codec_realtek hdaudioC1D0:    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[  129.684065] snd_hda_codec_realtek hdaudioC1D0:    hp_outs=1 (0x21/0x0/0x0/0x0/0x0)
[  129.684066] snd_hda_codec_realtek hdaudioC1D0:    mono: mono_out=0x0
[  129.684068] snd_hda_codec_realtek hdaudioC1D0:    inputs:
[  129.684070] snd_hda_codec_realtek hdaudioC1D0:      Mic=0x19
[  129.684071] snd_hda_codec_realtek hdaudioC1D0:      Internal Mic=0x12
[  129.735202] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1b.0/sound/card1/input15
[  129.735295] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card1/input16
[  131.219873] bpfilter: Loaded bpfilter_umh pid 764
[  131.220172] Started bpfilter
[  132.791617] audit: type=1400 audit(1597220653.736:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/man" pid=772 comm="apparmor_parser"
[  132.792352] audit: type=1400 audit(1597220653.736:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_filter" pid=772 comm="apparmor_parser"
[  132.793371] audit: type=1400 audit(1597220653.736:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_groff" pid=772 comm="apparmor_parser"
[  132.900426] audit: type=1400 audit(1597220653.844:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/dhcpd" pid=771 comm="apparmor_parser"
[  132.949808] audit: type=1400 audit(1597220653.892:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="libreoffice-senddoc" pid=785 comm="apparmor_parser"
[  132.978739] ax88179_178a 2-4.4.4.3:1.0 enx908d78043327: ax88179 - Link status is: 1
[  132.983548] IPv6: ADDRCONF(NETDEV_CHANGE): enx908d78043327: link becomes ready
[  132.987402] audit: type=1400 audit(1597220653.932:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/cups-browsed" pid=784 comm="apparmor_parser"
[  133.015907] audit: type=1400 audit(1597220653.960:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="ippusbxd" pid=786 comm="apparmor_parser"
[  133.190332] audit: type=1400 audit(1597220654.136:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/cups/backend/cups-pdf" pid=774 comm="apparmor_parser"
[  133.193096] audit: type=1400 audit(1597220654.136:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/ntpd" pid=788 comm="apparmor_parser"
[  133.194123] audit: type=1400 audit(1597220654.140:11): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/cupsd" pid=774 comm="apparmor_parser"
[  140.513194] kauditd_printk_skb: 18 callbacks suppressed
[  140.513195] audit: type=1400 audit(1597220661.456:30): apparmor="STATUS" operation="profile_load" profile="unconfined" name="libreoffice-soffice" pid=773 comm="apparmor_parser"
[  140.514609] audit: type=1400 audit(1597220661.460:31): apparmor="STATUS" operation="profile_load" profile="unconfined" name="libreoffice-soffice//gpg" pid=773 comm="apparmor_parser"
[  150.443334] audit: type=1400 audit(1597220671.388:32): apparmor="DENIED" operation="capable" profile="/usr/sbin/cups-browsed" pid=803 comm="cups-browsed" capability=23  capname="sys_nice"
[  181.443692] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[  181.443694] Bluetooth: BNEP filters: protocol multicast
[  181.443698] Bluetooth: BNEP socket layer initialized
[  698.334967] usb 2-1: USB disconnect, device number 2
[ 4759.298388] perf: interrupt took too long (2506 > 2500), lowering kernel.perf_event_max_sample_rate to 79750
[ 7981.203457] perf: interrupt took too long (3133 > 3132), lowering kernel.perf_event_max_sample_rate to 63750
[ 8263.612587] usb 2-2: USB disconnect, device number 3
[ 8263.628508] blk_update_request: I/O error, dev sdb, sector 2678161400 op 0x1:(WRITE) flags 0x0 phys_seg 1674 prio class 0
[ 8263.628515] Buffer I/O error on dev sdb, logical block 2678161400, lost async page write
[ 8263.628519] Buffer I/O error on dev sdb, logical block 2678161401, lost async page write
[ 8263.628521] Buffer I/O error on dev sdb, logical block 2678161402, lost async page write
[ 8263.628524] Buffer I/O error on dev sdb, logical block 2678161403, lost async page write
[ 8263.628526] Buffer I/O error on dev sdb, logical block 2678161404, lost async page write
[ 8263.628528] Buffer I/O error on dev sdb, logical block 2678161405, lost async page write
[ 8263.628530] Buffer I/O error on dev sdb, logical block 2678161406, lost async page write
[ 8263.628533] Buffer I/O error on dev sdb, logical block 2678161407, lost async page write
[ 8263.628538] Buffer I/O error on dev sdb, logical block 2678161408, lost async page write
[ 8263.628541] Buffer I/O error on dev sdb, logical block 2678161409, lost async page write
[ 8263.629271] blk_update_request: I/O error, dev sdb, sector 2678163074 op 0x1:(WRITE) flags 0x4000 phys_seg 2048 prio class 0
[ 8263.630073] blk_update_request: I/O error, dev sdb, sector 2678165122 op 0x1:(WRITE) flags 0x0 phys_seg 6 prio class 0
[ 8263.630146] blk_update_request: I/O error, dev sdb, sector 2678165128 op 0x1:(WRITE) flags 0x800 phys_seg 112 prio class 0
[ 8263.630879] sd 4:0:0:0: [sdb] Synchronizing SCSI cache
[ 8263.630912] sd 4:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
[ 8263.630974] scsi 4:0:0:0: rejecting I/O to dead device
[ 8263.630980] blk_update_request: I/O error, dev sdb, sector 2678165240 op 0x1:(WRITE) flags 0x0 phys_seg 104 prio class 0
[ 8275.232507] usb 2-2: new SuperSpeed Gen 1 USB device number 10 using xhci_hcd
[ 8275.253388] usb 2-2: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
[ 8275.253394] usb 2-2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[ 8275.253398] usb 2-2: Product: Ugreen Storage Device
[ 8275.253401] usb 2-2: Manufacturer: Ugreen
[ 8275.253403] usb 2-2: SerialNumber: 00000000DEF0
[ 8275.255665] usb 2-2: UAS is ignored for this device, using usb-storage instead
[ 8275.255672] usb-storage 2-2:1.0: USB Mass Storage device detected
[ 8275.255979] usb-storage 2-2:1.0: Quirks match for vid 174c pid 55aa: c00000
[ 8275.256108] scsi host3: usb-storage 2-2:1.0
[ 8276.275578] scsi 3:0:0:0: Direct-Access     Samsung  SSD 860 QVO 4TB  0    PQ: 0 ANSI: 6
[ 8276.276416] sd 3:0:0:0: Attached scsi generic sg1 type 0
[ 8276.276856] sd 3:0:0:0: [sdb] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
[ 8276.277115] sd 3:0:0:0: [sdb] Write Protect is off
[ 8276.277123] sd 3:0:0:0: [sdb] Mode Sense: 43 00 00 00
[ 8276.277402] sd 3:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 8276.299183] sd 3:0:0:0: [sdb] Attached SCSI disk

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Greg KH]

On Sun, Aug 16, 2020 at 05:31:09PM +0200, Patrik Nilsson wrote:
> [ 7981.203457] perf: interrupt took too long (3133 > 3132), lowering kernel.perf_event_max_sample_rate to 63750
> [ 8263.612587] usb 2-2: USB disconnect, device number 3

Your device disconnected itself from the bus, that is an electrical
thing that the kernel can not control/prevent/change.

> [ 8263.628508] blk_update_request: I/O error, dev sdb, sector 2678161400 op 0x1:(WRITE) flags 0x0 phys_seg 1674 prio class 0
> [ 8263.628515] Buffer I/O error on dev sdb, logical block 2678161400, lost async page write
> [ 8263.628519] Buffer I/O error on dev sdb, logical block 2678161401, lost async page write
> [ 8263.628521] Buffer I/O error on dev sdb, logical block 2678161402, lost async page write
> [ 8263.628524] Buffer I/O error on dev sdb, logical block 2678161403, lost async page write
> [ 8263.628526] Buffer I/O error on dev sdb, logical block 2678161404, lost async page write
> [ 8263.628528] Buffer I/O error on dev sdb, logical block 2678161405, lost async page write
> [ 8263.628530] Buffer I/O error on dev sdb, logical block 2678161406, lost async page write
> [ 8263.628533] Buffer I/O error on dev sdb, logical block 2678161407, lost async page write
> [ 8263.628538] Buffer I/O error on dev sdb, logical block 2678161408, lost async page write
> [ 8263.628541] Buffer I/O error on dev sdb, logical block 2678161409, lost async page write
> [ 8263.629271] blk_update_request: I/O error, dev sdb, sector 2678163074 op 0x1:(WRITE) flags 0x4000 phys_seg 2048 prio class 0
> [ 8263.630073] blk_update_request: I/O error, dev sdb, sector 2678165122 op 0x1:(WRITE) flags 0x0 phys_seg 6 prio class 0
> [ 8263.630146] blk_update_request: I/O error, dev sdb, sector 2678165128 op 0x1:(WRITE) flags 0x800 phys_seg 112 prio class 0
> [ 8263.630879] sd 4:0:0:0: [sdb] Synchronizing SCSI cache
> [ 8263.630912] sd 4:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
> [ 8263.630974] scsi 4:0:0:0: rejecting I/O to dead device
> [ 8263.630980] blk_update_request: I/O error, dev sdb, sector 2678165240 op 0x1:(WRITE) flags 0x0 phys_seg 104 prio class 0
> [ 8275.232507] usb 2-2: new SuperSpeed Gen 1 USB device number 10 using xhci_hcd

And now it came back.

So I really think this device is just sucking up too much power and
eventually the hub disconnects it (or it disconnects itself by dropping
the voltage too low) and then adds itself again.

If you write data "slower" to it, it's fine, but by writing 0's to your
device, it's constantly hammered and that's the hardest thing for a
flash device to handle (flash starts out at all bits set and then drops
them as needed to 0, so you are asking for the most work possible to be
done by the flash chips.)

It might be better to just buy a different disk drive :)

sorry,

greg k-h

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Patrik Nilsson]

Hi Greg,

Thank you for debugging the issue!

Is there a way to set a speed limit to the device? I mean like setting 
the usb-quirks. (i.e. usb-storage.quirks=174c:55aa:u)

Instead of buying a new enclosing for the drive... :(

Best regards,
Patrik

On 18/08/2020 10:55, Greg KH wrote:
> On Sun, Aug 16, 2020 at 05:31:09PM +0200, Patrik Nilsson wrote:
>> [ 7981.203457] perf: interrupt took too long (3133 > 3132), lowering kernel.perf_event_max_sample_rate to 63750
>> [ 8263.612587] usb 2-2: USB disconnect, device number 3
> Your device disconnected itself from the bus, that is an electrical
> thing that the kernel can not control/prevent/change.
>
>> [ 8263.628508] blk_update_request: I/O error, dev sdb, sector 2678161400 op 0x1:(WRITE) flags 0x0 phys_seg 1674 prio class 0
>> [ 8263.628515] Buffer I/O error on dev sdb, logical block 2678161400, lost async page write
>> [ 8263.628519] Buffer I/O error on dev sdb, logical block 2678161401, lost async page write
>> [ 8263.628521] Buffer I/O error on dev sdb, logical block 2678161402, lost async page write
>> [ 8263.628524] Buffer I/O error on dev sdb, logical block 2678161403, lost async page write
>> [ 8263.628526] Buffer I/O error on dev sdb, logical block 2678161404, lost async page write
>> [ 8263.628528] Buffer I/O error on dev sdb, logical block 2678161405, lost async page write
>> [ 8263.628530] Buffer I/O error on dev sdb, logical block 2678161406, lost async page write
>> [ 8263.628533] Buffer I/O error on dev sdb, logical block 2678161407, lost async page write
>> [ 8263.628538] Buffer I/O error on dev sdb, logical block 2678161408, lost async page write
>> [ 8263.628541] Buffer I/O error on dev sdb, logical block 2678161409, lost async page write
>> [ 8263.629271] blk_update_request: I/O error, dev sdb, sector 2678163074 op 0x1:(WRITE) flags 0x4000 phys_seg 2048 prio class 0
>> [ 8263.630073] blk_update_request: I/O error, dev sdb, sector 2678165122 op 0x1:(WRITE) flags 0x0 phys_seg 6 prio class 0
>> [ 8263.630146] blk_update_request: I/O error, dev sdb, sector 2678165128 op 0x1:(WRITE) flags 0x800 phys_seg 112 prio class 0
>> [ 8263.630879] sd 4:0:0:0: [sdb] Synchronizing SCSI cache
>> [ 8263.630912] sd 4:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
>> [ 8263.630974] scsi 4:0:0:0: rejecting I/O to dead device
>> [ 8263.630980] blk_update_request: I/O error, dev sdb, sector 2678165240 op 0x1:(WRITE) flags 0x0 phys_seg 104 prio class 0
>> [ 8275.232507] usb 2-2: new SuperSpeed Gen 1 USB device number 10 using xhci_hcd
> And now it came back.
>
> So I really think this device is just sucking up too much power and
> eventually the hub disconnects it (or it disconnects itself by dropping
> the voltage too low) and then adds itself again.
>
> If you write data "slower" to it, it's fine, but by writing 0's to your
> device, it's constantly hammered and that's the hardest thing for a
> flash device to handle (flash starts out at all bits set and then drops
> them as needed to 0, so you are asking for the most work possible to be
> done by the flash chips.)
>
> It might be better to just buy a different disk drive :)
>
> sorry,
>
> greg k-h

-- 
PGP-key fingerprint: 1B30 7F61 AF9E 538A FCD6  2BE7 CED7 B0E4 3BF9 8D6C

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Alan Stern]

On Tue, Aug 18, 2020 at 08:22:55PM +0200, Patrik Nilsson wrote:
> Hi Greg,
> 
> Thank you for debugging the issue!
> 
> Is there a way to set a speed limit to the device? I mean like setting the
> usb-quirks. (i.e. usb-storage.quirks=174c:55aa:u)
> 
> Instead of buying a new enclosing for the drive... :(

You can try setting usb-storage.quirks=174c:55aa:g or :m.  They will 
slow down the data transfer rates.  The :g flag is used by the uas 
driver and the :m flag is used by usb-storage.

Alan Stern

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Patrik Nilsson]

Hi Alan,

Unfortunately the options you suggested didn't help.

The patch below works and is tested with Ubuntu kernel 5.4.0.47.50.

I have stress tested the usb system. To the USB is now seven mechanical 
hard disks and two ssd disks connected. Six processes are at the same 
time writing random data to the disks. One of them is to the ssd disk I 
couldn't write data to before without it failed. Also the other usb-ssd 
disk is my root partition.

Before I applied the patch, my root partition sometimes failed to be 
kept mounted. Now I have not had any crashes.

This is a quick fix for hard disks, but working. It continued to work 
when I started three virtualbox guests and let them also do work. The 
guests' hard disks is on my usb-root partition.

It doesn't work if I also use my usb2ethernet adapter (ID 2001:4a00 
D-Link Corp.), although my root partition and two randomize tests 
survived. Maybe a much larger timeout in this case will help? But this I 
don't find as a good solution.

The behavior is the same on the other (much slower) computer with a 
different usb hub. I have also tested it with exactly the same setup as 
earlier, with no mechanical hard disks, and it works with the patch and 
not without it.

Any suggestion on how to solve this? In a good way.

Best regards,
Patrik

---start of diff---
diff --git a/drivers/usb/core/hub.c b/drivers/usb/core/hub.c
index 5b768b80d1ee..3c550934815c 100644
--- a/drivers/usb/core/hub.c
+++ b/drivers/usb/core/hub.c
@@ -105,7 +105,7 @@ MODULE_PARM_DESC(use_both_schemes,
  DECLARE_RWSEM(ehci_cf_port_reset_rwsem);
  EXPORT_SYMBOL_GPL(ehci_cf_port_reset_rwsem);

-#define HUB_DEBOUNCE_TIMEOUT    2000
+#define HUB_DEBOUNCE_TIMEOUT    10000
  #define HUB_DEBOUNCE_STEP      25
  #define HUB_DEBOUNCE_STABLE     100

diff --git a/include/linux/usb.h b/include/linux/usb.h
index 20c555db4621..e64d441bb78f 100644
--- a/include/linux/usb.h
+++ b/include/linux/usb.h
@@ -1841,8 +1841,8 @@ extern int usb_set_configuration(struct usb_device 
*dev, int configuration);
   * USB identifies 5 second timeouts, maybe more in a few cases, and a few
   * slow devices (like some MGE Ellipse UPSes) actually push that limit.
   */
-#define USB_CTRL_GET_TIMEOUT    5000
-#define USB_CTRL_SET_TIMEOUT    5000
+#define USB_CTRL_GET_TIMEOUT    10000
+#define USB_CTRL_SET_TIMEOUT    10000


  /**
---end of diff---


On 18/08/2020 20:34, Alan Stern wrote:
> On Tue, Aug 18, 2020 at 08:22:55PM +0200, Patrik Nilsson wrote:
>> Hi Greg,
>>
>> Thank you for debugging the issue!
>>
>> Is there a way to set a speed limit to the device? I mean like setting the
>> usb-quirks. (i.e. usb-storage.quirks=174c:55aa:u)
>>
>> Instead of buying a new enclosing for the drive... :(
> You can try setting usb-storage.quirks=174c:55aa:g or :m.  They will
> slow down the data transfer rates.  The :g flag is used by the uas
> driver and the :m flag is used by usb-storage.
>
> Alan Stern

-- 
PGP-key fingerprint: 1B30 7F61 AF9E 538A FCD6  2BE7 CED7 B0E4 3BF9 8D6C

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Alan Stern]

On Tue, Sep 15, 2020 at 06:43:49PM +0200, Patrik Nilsson wrote:
> Hi Alan,
> 
> Unfortunately the options you suggested didn't help.
> 
> The patch below works and is tested with Ubuntu kernel 5.4.0.47.50.
> 
> I have stress tested the usb system. To the USB is now seven mechanical hard
> disks and two ssd disks connected. Six processes are at the same time
> writing random data to the disks. One of them is to the ssd disk I couldn't
> write data to before without it failed. Also the other usb-ssd disk is my
> root partition.
> 
> Before I applied the patch, my root partition sometimes failed to be kept
> mounted. Now I have not had any crashes.
> 
> This is a quick fix for hard disks, but working. It continued to work when I
> started three virtualbox guests and let them also do work. The guests' hard
> disks is on my usb-root partition.
> 
> It doesn't work if I also use my usb2ethernet adapter (ID 2001:4a00 D-Link
> Corp.), although my root partition and two randomize tests survived. Maybe a
> much larger timeout in this case will help? But this I don't find as a good
> solution.
> 
> The behavior is the same on the other (much slower) computer with a
> different usb hub. I have also tested it with exactly the same setup as
> earlier, with no mechanical hard disks, and it works with the patch and not
> without it.

Is there any way you can capture a usbmon trace that shows the problem?

> Any suggestion on how to solve this? In a good way.
> 
> Best regards,
> Patrik
> 
> ---start of diff---
> diff --git a/drivers/usb/core/hub.c b/drivers/usb/core/hub.c
> index 5b768b80d1ee..3c550934815c 100644
> --- a/drivers/usb/core/hub.c
> +++ b/drivers/usb/core/hub.c
> @@ -105,7 +105,7 @@ MODULE_PARM_DESC(use_both_schemes,
>  DECLARE_RWSEM(ehci_cf_port_reset_rwsem);
>  EXPORT_SYMBOL_GPL(ehci_cf_port_reset_rwsem);
> 
> -#define HUB_DEBOUNCE_TIMEOUT    2000
> +#define HUB_DEBOUNCE_TIMEOUT    10000
>  #define HUB_DEBOUNCE_STEP      25
>  #define HUB_DEBOUNCE_STABLE     100
> 
> diff --git a/include/linux/usb.h b/include/linux/usb.h
> index 20c555db4621..e64d441bb78f 100644
> --- a/include/linux/usb.h
> +++ b/include/linux/usb.h
> @@ -1841,8 +1841,8 @@ extern int usb_set_configuration(struct usb_device
> *dev, int configuration);
>   * USB identifies 5 second timeouts, maybe more in a few cases, and a few
>   * slow devices (like some MGE Ellipse UPSes) actually push that limit.
>   */
> -#define USB_CTRL_GET_TIMEOUT    5000
> -#define USB_CTRL_SET_TIMEOUT    5000
> +#define USB_CTRL_GET_TIMEOUT    10000
> +#define USB_CTRL_SET_TIMEOUT    10000

What happens if you leave these two lines unchanged and increase only 
the HUB_DEBOUNCE_TIMEOUT value?

Alan Stern

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Patrik Nilsson]

Dear Alan,

After long time testing it seems that changing the TIMEOUT values did 
help somewhat, but not completely.

I tested two SSD and wrote to them at the same time:

# dd if=/dev/zero of=/dev/sdd bs=$((10*1024*1024))

# dd if=/dev/zero of=/dev/sdg bs=$((10*1024*1024)) # different bash terminal

The synchronize write gives a lot of errors as the log below (1) shows

Running big with blocks in synchronized mode gives the same kind of error:

# dd if=/dev/zero of=/dev/sdi bs=$((10*1024*1024)) oflag=dsync # this is 
another SSD bought for this test
dd: error writing '/dev/sdi': Input/output error
9+0 records in
8+0 records out
83886080 bytes (84 MB, 80 MiB) copied, 185.585 s, 452 kB/s

When synchronized data transfer is done to sdi and sdg in a small block 
size everything works out as it should, but takes a long time.

# dd if=/dev/zero of=/dev/sdg bs=1024 oflag=dsync
56897100+0 records in
56897100+0 records out
58262630400 bytes (58 GB, 54 GiB) copied, 74235.8 s, 785 kB/s
[...]
dd: error writing '/dev/sdg': No space left on device
488386585+0 records in
488386584+0 records out
500107862016 bytes (500 GB, 466 GiB) copied, 702184 s, 712 kB/s

I have been unable to catch this kind of error leading to a crash.

The shutdown of the test computer did show the same kind of errors as 
the below log. It couldn't write asynchronous data.

Best regards,
Patrik


(1) Log:

[73918.351448] sd 9:0:0:0: [sdg] tag#19 CDB: Write(10) 2a 00 1d 6d 9d c0 
00 04 00 00
[73918.351449] blk_update_request: I/O error, dev sdg, sector 493723072 
op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[73918.351552] sd 9:0:0:0: [sdg] tag#20 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[73918.351554] sd 9:0:0:0: [sdg] tag#20 CDB: Write(10) 2a 00 1d 6d 91 c0 
00 04 00 00
[73918.351555] blk_update_request: I/O error, dev sdg, sector 493720000 
op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[73918.351668] sd 9:0:0:0: [sdg] tag#21 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[73918.351669] sd 9:0:0:0: [sdg] tag#21 CDB: Write(10) 2a 00 1d 6d 7d c0 
00 04 00 00
[73918.351670] blk_update_request: I/O error, dev sdg, sector 493714880 
op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[73918.351776] sd 9:0:0:0: [sdg] tag#22 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[73918.351777] sd 9:0:0:0: [sdg] tag#22 CDB: Write(10) 2a 00 1d 6d 81 c0 
00 04 00 00
[73918.351778] blk_update_request: I/O error, dev sdg, sector 493715904 
op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[73918.351880] sd 9:0:0:0: [sdg] tag#23 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[73918.351881] sd 9:0:0:0: [sdg] tag#23 CDB: Write(10) 2a 00 1d 6d 8d c0 
00 04 00 00
[73918.351882] blk_update_request: I/O error, dev sdg, sector 493718976 
op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[73918.351991] sd 9:0:0:0: [sdg] tag#24 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[73918.351993] sd 9:0:0:0: [sdg] tag#24 CDB: Write(10) 2a 00 1d 6d 85 c0 
00 04 00 00
[73918.351994] blk_update_request: I/O error, dev sdg, sector 493716928 
op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[73918.352095] sd 9:0:0:0: [sdg] tag#25 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[73918.352097] sd 9:0:0:0: [sdg] tag#25 CDB: Write(10) 2a 00 1d 6d 89 c0 
00 04 00 00
[73918.352098] blk_update_request: I/O error, dev sdg, sector 493717952 
op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[73918.353609] sd 9:0:0:0: [sdg] tag#26 data cmplt err -71 uas-tag 1 
inflight: CMD
[73918.353619] sd 9:0:0:0: [sdg] tag#26 CDB: Write(10) 2a 00 1d 6d d9 c0 
00 04 00 00
[73948.916831] sd 9:0:0:0: [sdg] tag#27 uas_eh_abort_handler 0 uas-tag 2 
inflight: CMD OUT

Here is more errors with /dev/sdg, not with dd.

[1971052.229416] blk_update_request: I/O error, dev sdg, sector 
686779192 op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[1971052.229422] buffer_io_error: 2806 callbacks suppressed
[1971052.229424] Buffer I/O error on dev sdg, logical block 85847399, 
lost async page write
[1971052.229432] Buffer I/O error on dev sdg, logical block 85847400, 
lost async page write
[1971052.229435] Buffer I/O error on dev sdg, logical block 85847401, 
lost async page write
[1971052.229438] Buffer I/O error on dev sdg, logical block 85847402, 
lost async page write
[1971052.229441] Buffer I/O error on dev sdg, logical block 85847403, 
lost async page write
[1971052.229444] Buffer I/O error on dev sdg, logical block 85847404, 
lost async page write
[1971052.229446] Buffer I/O error on dev sdg, logical block 85847405, 
lost async page write
[1971052.229449] Buffer I/O error on dev sdg, logical block 85847406, 
lost async page write
[1971052.229452] Buffer I/O error on dev sdg, logical block 85847407, 
lost async page write
[1971052.229454] Buffer I/O error on dev sdg, logical block 85847408, 
lost async page write
[1971052.229644] sd 9:0:0:0: [sdg] tag#1 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[1971052.229647] sd 9:0:0:0: [sdg] tag#1 CDB: Write(10) 2a 00 28 ef 6f 
38 00 04 00 00
[1971052.229650] blk_update_request: I/O error, dev sdg, sector 
686780216 op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[1971052.229800] sd 9:0:0:0: [sdg] tag#2 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[1971052.229802] sd 9:0:0:0: [sdg] tag#2 CDB: Write(10) 2a 00 28 ef 73 
38 00 04 00 00
[1971052.229804] blk_update_request: I/O error, dev sdg, sector 
686781240 op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[1971052.229953] sd 9:0:0:0: [sdg] tag#3 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[1971052.229955] sd 9:0:0:0: [sdg] tag#3 CDB: Write(10) 2a 00 28 ef 7b 
38 00 04 00 00
[1971052.229957] blk_update_request: I/O error, dev sdg, sector 
686783288 op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[1971052.230107] sd 9:0:0:0: [sdg] tag#4 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[1971052.230109] sd 9:0:0:0: [sdg] tag#4 CDB: Write(10) 2a 00 28 ef 7f 
38 00 04 00 00
[1971052.230111] blk_update_request: I/O error, dev sdg, sector 
686784312 op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[1971052.230255] sd 9:0:0:0: [sdg] tag#5 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[1971052.230258] sd 9:0:0:0: [sdg] tag#5 CDB: Write(10) 2a 00 28 ef 83 
38 00 04 00 00
[1971052.230260] blk_update_request: I/O error, dev sdg, sector 
686785336 op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[1971052.230404] sd 9:0:0:0: [sdg] tag#6 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[1971052.230406] sd 9:0:0:0: [sdg] tag#6 CDB: Write(10) 2a 00 28 ef 87 
38 00 04 00 00
[1971052.230408] blk_update_request: I/O error, dev sdg, sector 
686786360 op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[1971052.230559] sd 9:0:0:0: [sdg] tag#7 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[1971052.230561] sd 9:0:0:0: [sdg] tag#7 CDB: Write(10) 2a 00 28 ef 8b 
38 00 04 00 00
[1971052.230563] blk_update_request: I/O error, dev sdg, sector 
686787384 op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[1971052.230710] sd 9:0:0:0: [sdg] tag#8 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[1971052.230712] sd 9:0:0:0: [sdg] tag#8 CDB: Write(10) 2a 00 28 ef 8f 
38 00 04 00 00
[1971052.230714] blk_update_request: I/O error, dev sdg, sector 
686788408 op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[1971052.230855] sd 9:0:0:0: [sdg] tag#9 FAILED Result: 
hostbyte=DID_RESET driverbyte=DRIVER_OK
[1971052.230857] sd 9:0:0:0: [sdg] tag#9 CDB: Write(10) 2a 00 28 ef 4f 
38 00 04 00 00
[1971052.230859] blk_update_request: I/O error, dev sdg, sector 
686772024 op 0x1:(WRITE) flags 0x104000 phys_seg 128 prio class 0
[1971052.233748] sd 9:0:0:0: [sdg] tag#21 data cmplt err -71 uas-tag 1 
inflight: CMD
[1971052.233754] sd 9:0:0:0: [sdg] tag#21 CDB: Write(10) 2a 00 28 ef 93 
38 00 04 00 00
[1971082.763230] sd 9:0:0:0: [sdg] tag#29 uas_eh_abort_handler 0 uas-tag 
7 inflight: CMD OUT
[1971082.763239] sd 9:0:0:0: [sdg] tag#29 CDB: Write(10) 2a 00 28 ef ab 
38 00 04 00 00
[1971082.764519] sd 9:0:0:0: [sdg] tag#28 uas_eh_abort_handler 0 uas-tag 
6 inflight: CMD OUT
[1971082.764526] sd 9:0:0:0: [sdg] tag#28 CDB: Write(10) 2a 00 28 ef a7 
38 00 04 00 00
[1971082.767991] sd 9:0:0:0: [sdg] tag#24 uas_eh_abort_handler 0 uas-tag 
5 inflight: CMD OUT
[1971082.768000] sd 9:0:0:0: [sdg] tag#24 CDB: Write(10) 2a 00 28 ef a3 
38 00 04 00 00
[1971082.771461] sd 9:0:0:0: [sdg] tag#23 uas_eh_abort_handler 0 uas-tag 
3 inflight: CMD OUT
[1971082.771467] sd 9:0:0:0: [sdg] tag#23 CDB: Write(10) 2a 00 28 ef 9b 
38 00 04 00 00
[1971082.774960] sd 9:0:0:0: [sdg] tag#22 uas_eh_abort_handler 0 uas-tag 
2 inflight: CMD OUT
[1971082.774965] sd 9:0:0:0: [sdg] tag#22 CDB: Write(10) 2a 00 28 ef 97 
38 00 04 00 00
[1971082.778462] sd 9:0:0:0: [sdg] tag#21 uas_eh_abort_handler 0 uas-tag 
1 inflight: CMD
[1971082.778467] sd 9:0:0:0: [sdg] tag#21 CDB: Write(10) 2a 00 28 ef 93 
38 00 04 00 00
[1971082.778473] sd 9:0:0:0: [sdg] tag#20 uas_eh_abort_handler 0 uas-tag 
4 inflight: CMD OUT
[1971082.778477] sd 9:0:0:0: [sdg] tag#20 CDB: Write(10) 2a 00 28 ef 9f 
38 00 04 00 00
[1971082.781959] sd 9:0:0:0: [sdg] tag#15 uas_eh_abort_handler 0 uas-tag 
22 inflight: CMD OUT
[1971082.781965] sd 9:0:0:0: [sdg] tag#15 CDB: Write(10) 2a 00 28 ef e7 
38 00 04 00 00
[1971082.785456] sd 9:0:0:0: [sdg] tag#14 uas_eh_abort_handler 0 uas-tag 
21 inflight: CMD OUT
[1971082.785469] sd 9:0:0:0: [sdg] tag#14 CDB: Write(10) 2a 00 28 ef e3 
38 00 04 00 00
[1971082.788922] sd 9:0:0:0: [sdg] tag#13 uas_eh_abort_handler 0 uas-tag 
20 inflight: CMD OUT
[1971082.788924] sd 9:0:0:0: [sdg] tag#13 CDB: Write(10) 2a 00 28 ef df 
38 00 04 00 00
[1971082.792452] sd 9:0:0:0: [sdg] tag#11 uas_eh_abort_handler 0 uas-tag 
19 inflight: CMD OUT
[1971082.792454] sd 9:0:0:0: [sdg] tag#11 CDB: Write(10) 2a 00 28 ef db 
38 00 04 00 00
[1971082.795975] sd 9:0:0:0: [sdg] tag#10 uas_eh_abort_handler 0 uas-tag 
18 inflight: CMD OUT
[1971082.795979] sd 9:0:0:0: [sdg] tag#10 CDB: Write(10) 2a 00 28 ef d7 
38 00 04 00 00
[1971082.799429] sd 9:0:0:0: [sdg] tag#9 uas_eh_abort_handler 0 uas-tag 
17 inflight: CMD OUT
[1971082.799433] sd 9:0:0:0: [sdg] tag#9 CDB: Write(10) 2a 00 28 ef d3 
38 00 04 00 00
[1971082.802920] sd 9:0:0:0: [sdg] tag#8 uas_eh_abort_handler 0 uas-tag 
16 inflight: CMD OUT
[1971082.802923] sd 9:0:0:0: [sdg] tag#8 CDB: Write(10) 2a 00 28 ef cf 
38 00 04 00 00
[1971082.806430] sd 9:0:0:0: [sdg] tag#7 uas_eh_abort_handler 0 uas-tag 
15 inflight: CMD OUT
[1971082.806433] sd 9:0:0:0: [sdg] tag#7 CDB: Write(10) 2a 00 28 ef cb 
38 00 04 00 00
[1971082.809929] sd 9:0:0:0: [sdg] tag#6 uas_eh_abort_handler 0 uas-tag 
14 inflight: CMD OUT
[1971082.809933] sd 9:0:0:0: [sdg] tag#6 CDB: Write(10) 2a 00 28 ef c7 
38 00 04 00 00
[1971082.813446] sd 9:0:0:0: [sdg] tag#5 uas_eh_abort_handler 0 uas-tag 
13 inflight: CMD OUT
[1971082.813451] sd 9:0:0:0: [sdg] tag#5 CDB: Write(10) 2a 00 28 ef c3 
38 00 04 00 00
[1971082.816988] sd 9:0:0:0: [sdg] tag#4 uas_eh_abort_handler 0 uas-tag 
12 inflight: CMD OUT
[1971082.816993] sd 9:0:0:0: [sdg] tag#4 CDB: Write(10) 2a 00 28 ef bf 
38 00 04 00 00
[1971082.820461] sd 9:0:0:0: [sdg] tag#3 uas_eh_abort_handler 0 uas-tag 
11 inflight: CMD OUT
[1971082.820465] sd 9:0:0:0: [sdg] tag#3 CDB: Write(10) 2a 00 28 ef bb 
38 00 04 00 00
[1971082.823946] sd 9:0:0:0: [sdg] tag#2 uas_eh_abort_handler 0 uas-tag 
10 inflight: CMD OUT
[1971082.823952] sd 9:0:0:0: [sdg] tag#2 CDB: Write(10) 2a 00 28 ef b7 
38 00 04 00 00
[1971082.827474] sd 9:0:0:0: [sdg] tag#1 uas_eh_abort_handler 0 uas-tag 
9 inflight: CMD OUT
[1971082.827479] sd 9:0:0:0: [sdg] tag#1 CDB: Write(10) 2a 00 28 ef b3 
38 00 04 00 00
[1971082.830945] sd 9:0:0:0: [sdg] tag#0 uas_eh_abort_handler 0 uas-tag 
8 inflight: CMD OUT
[1971082.830951] sd 9:0:0:0: [sdg] tag#0 CDB: Write(10) 2a 00 28 ef af 
38 00 04 00 00
[1971082.851236] scsi host9: uas_eh_device_reset_handler start
[1971082.931767] usb 2-4.4.4.4.3: reset SuperSpeed Gen 1 USB device 
number 9 using xhci_hcd
[1971082.961643] scsi host9: uas_eh_device_reset_handler success
[1971082.980120] sd 9:0:0:0: [sdg] tag#20 data cmplt err -71 uas-tag 1 
inflight: CMD
[1971082.980128] sd 9:0:0:0: [sdg] tag#20 CDB: Write(10) 2a 00 28 ef af 
38 00 04 00 00
[1971113.494570] scsi host9: uas_eh_device_reset_handler start
[1971113.568509] sd 9:0:0:0: [sdg] tag#20 uas_zap_pending 0 uas-tag 1 
inflight: CMD
[1971113.568518] sd 9:0:0:0: [sdg] tag#20 CDB: Write(10) 2a 00 28 ef af 
38 00 04 00 00
[1971113.568524] sd 9:0:0:0: [sdg] tag#21 uas_zap_pending 0 uas-tag 2 
inflight: CMD
[1971113.568528] sd 9:0:0:0: [sdg] tag#21 CDB: Write(10) 2a 00 28 ef b3 
38 00 04 00 00
[1971113.568533] sd 9:0:0:0: [sdg] tag#22 uas_zap_pending 0 uas-tag 3 
inflight: CMD
[1971113.568537] sd 9:0:0:0: [sdg] tag#22 CDB: Write(10) 2a 00 28 ef b7 
38 00 04 00 00
[1971113.568542] sd 9:0:0:0: [sdg] tag#23 uas_zap_pending 0 uas-tag 4 
inflight: CMD
[1971113.568545] sd 9:0:0:0: [sdg] tag#23 CDB: Write(10) 2a 00 28 ef bb 
38 00 04 00 00
[1971113.568550] sd 9:0:0:0: [sdg] tag#25 uas_zap_pending 0 uas-tag 5 
inflight: CMD
[1971113.568554] sd 9:0:0:0: [sdg] tag#25 CDB: Write(10) 2a 00 28 ef bf 
38 00 04 00 00
[1971113.568559] sd 9:0:0:0: [sdg] tag#26 uas_zap_pending 0 uas-tag 6 
inflight: CMD
[1971113.568563] sd 9:0:0:0: [sdg] tag#26 CDB: Write(10) 2a 00 28 ef c3 
38 00 04 00 00
[1971113.568567] sd 9:0:0:0: [sdg] tag#27 uas_zap_pending 0 uas-tag 7 
inflight: CMD
[1971113.568571] sd 9:0:0:0: [sdg] tag#27 CDB: Write(10) 2a 00 28 ef c7 
38 00 04 00 00
[1971113.568576] sd 9:0:0:0: [sdg] tag#24 uas_zap_pending 0 uas-tag 8 
inflight: CMD
[1971113.568579] sd 9:0:0:0: [sdg] tag#24 CDB: Write(10) 2a 00 28 ef cb 
38 00 04 00 00
[1971113.568584] sd 9:0:0:0: [sdg] tag#28 uas_zap_pending 0 uas-tag 9 
inflight: CMD
[1971113.568587] sd 9:0:0:0: [sdg] tag#28 CDB: Write(10) 2a 00 28 ef cf 
38 00 04 00 00
[1971113.568592] sd 9:0:0:0: [sdg] tag#29 uas_zap_pending 0 uas-tag 10 
inflight: CMD
[1971113.568596] sd 9:0:0:0: [sdg] tag#29 CDB: Write(10) 2a 00 28 ef d3 
38 00 04 00 00
[1971113.568601] sd 9:0:0:0: [sdg] tag#0 uas_zap_pending 0 uas-tag 11 
inflight: CMD
[1971113.568604] sd 9:0:0:0: [sdg] tag#0 CDB: Write(10) 2a 00 28 ef d7 
38 00 04 00 00
[1971113.568609] sd 9:0:0:0: [sdg] tag#1 uas_zap_pending 0 uas-tag 12 
inflight: CMD
[1971113.568613] sd 9:0:0:0: [sdg] tag#1 CDB: Write(10) 2a 00 28 ef db 
38 00 04 00 00
[1971113.568618] sd 9:0:0:0: [sdg] tag#2 uas_zap_pending 0 uas-tag 13 
inflight: CMD
[1971113.568621] sd 9:0:0:0: [sdg] tag#2 CDB: Write(10) 2a 00 28 ef df 
38 00 04 00 00
[1971113.568626] sd 9:0:0:0: [sdg] tag#3 uas_zap_pending 0 uas-tag 14 
inflight: CMD
[1971113.568630] sd 9:0:0:0: [sdg] tag#3 CDB: Write(10) 2a 00 28 ef e3 
38 00 04 00 00
[1971113.568635] sd 9:0:0:0: [sdg] tag#4 uas_zap_pending 0 uas-tag 15 
inflight: CMD
[1971113.568638] sd 9:0:0:0: [sdg] tag#4 CDB: Write(10) 2a 00 28 ef e7 
38 00 04 00 00
[1971113.568643] sd 9:0:0:0: [sdg] tag#5 uas_zap_pending 0 uas-tag 16 
inflight: CMD
[1971113.568647] sd 9:0:0:0: [sdg] tag#5 CDB: Write(10) 2a 00 28 ef 9f 
38 00 04 00 00
[1971113.568651] sd 9:0:0:0: [sdg] tag#6 uas_zap_pending 0 uas-tag 17 
inflight: CMD
[1971113.568655] sd 9:0:0:0: [sdg] tag#6 CDB: Write(10) 2a 00 28 ef 93 
38 00 04 00 00
[1971113.568660] sd 9:0:0:0: [sdg] tag#7 uas_zap_pending 0 uas-tag 18 
inflight: CMD
[1971113.568663] sd 9:0:0:0: [sdg] tag#7 CDB: Write(10) 2a 00 28 ef 97 
38 00 04 00 00
[1971113.568668] sd 9:0:0:0: [sdg] tag#8 uas_zap_pending 0 uas-tag 19 
inflight: CMD
[1971113.568671] sd 9:0:0:0: [sdg] tag#8 CDB: Write(10) 2a 00 28 ef 9b 
38 00 04 00 00
[1971113.568676] sd 9:0:0:0: [sdg] tag#9 uas_zap_pending 0 uas-tag 20 
inflight: CMD
[1971113.568680] sd 9:0:0:0: [sdg] tag#9 CDB: Write(10) 2a 00 28 ef a3 
38 00 04 00 00
[1971113.568684] sd 9:0:0:0: [sdg] tag#10 uas_zap_pending 0 uas-tag 21 
inflight: CMD
[1971113.568688] sd 9:0:0:0: [sdg] tag#10 CDB: Write(10) 2a 00 28 ef a7 
38 00 04 00 00
[1971113.568692] sd 9:0:0:0: [sdg] tag#11 uas_zap_pending 0 uas-tag 22 
inflight: CMD
[1971113.568696] sd 9:0:0:0: [sdg] tag#11 CDB: Write(10) 2a 00 28 ef ab 
38 00 04 00 00
[1971113.651031] usb 2-4.4.4.4.3: reset SuperSpeed Gen 1 USB device 
number 9 using xhci_hcd
[1971113.681609] scsi host9: uas_eh_device_reset_handler success
[1971113.695693] sd 9:0:0:0: [sdg] tag#24 data cmplt err -71 uas-tag 1 
inflight: CMD
[1971113.695702] sd 9:0:0:0: [sdg] tag#24 CDB: Write(10) 2a 00 28 ef d3 
38 00 04 00 00
[1971144.205921] scsi host9: uas_eh_device_reset_handler start
[1971144.278930] sd 9:0:0:0: [sdg] tag#24 uas_zap_pending 0 uas-tag 1 
inflight: CMD
[1971144.278940] sd 9:0:0:0: [sdg] tag#24 CDB: Write(10) 2a 00 28 ef d3 
38 00 04 00 00
[1971144.278946] sd 9:0:0:0: [sdg] tag#25 uas_zap_pending 0 uas-tag 2 
inflight: CMD
[1971144.278951] sd 9:0:0:0: [sdg] tag#25 CDB: Write(10) 2a 00 28 ef cf 
38 00 04 00 00
[1971144.278956] sd 9:0:0:0: [sdg] tag#26 uas_zap_pending 0 uas-tag 3 
inflight: CMD
[1971144.278960] sd 9:0:0:0: [sdg] tag#26 CDB: Write(10) 2a 00 28 ef c7 
38 00 04 00 00
[1971144.278965] sd 9:0:0:0: [sdg] tag#27 uas_zap_pending 0 uas-tag 4 
inflight: CMD
[1971144.278969] sd 9:0:0:0: [sdg] tag#27 CDB: Write(10) 2a 00 28 ef c3 
38 00 04 00 00
[1971144.278974] sd 9:0:0:0: [sdg] tag#28 uas_zap_pending 0 uas-tag 5 
inflight: CMD
[1971144.278978] sd 9:0:0:0: [sdg] tag#28 CDB: Write(10) 2a 00 28 ef bf 
38 00 04 00 00
[1971144.278983] sd 9:0:0:0: [sdg] tag#0 uas_zap_pending 0 uas-tag 6 
inflight: CMD
[1971144.278987] sd 9:0:0:0: [sdg] tag#0 CDB: Write(10) 2a 00 28 ef cb 
38 00 04 00 00
[1971144.278992] sd 9:0:0:0: [sdg] tag#1 uas_zap_pending 0 uas-tag 7 
inflight: CMD
[1971144.278996] sd 9:0:0:0: [sdg] tag#1 CDB: Write(10) 2a 00 28 ef bb 
38 00 04 00 00
[1971144.279000] sd 9:0:0:0: [sdg] tag#2 uas_zap_pending 0 uas-tag 8 
inflight: CMD
[1971144.279004] sd 9:0:0:0: [sdg] tag#2 CDB: Write(10) 2a 00 28 ef b7 
38 00 04 00 00
[1971144.279009] sd 9:0:0:0: [sdg] tag#3 uas_zap_pending 0 uas-tag 9 
inflight: CMD
[1971144.279012] sd 9:0:0:0: [sdg] tag#3 CDB: Write(10) 2a 00 28 ef b3 
38 00 04 00 00
[1971144.279017] sd 9:0:0:0: [sdg] tag#4 uas_zap_pending 0 uas-tag 10 
inflight: CMD
[1971144.279021] sd 9:0:0:0: [sdg] tag#4 CDB: Write(10) 2a 00 28 ef af 
38 00 04 00 00
[1971144.279025] sd 9:0:0:0: [sdg] tag#5 uas_zap_pending 0 uas-tag 11 
inflight: CMD
[1971144.279029] sd 9:0:0:0: [sdg] tag#5 CDB: Write(10) 2a 00 28 ef ab 
38 00 04 00 00
[1971144.279033] sd 9:0:0:0: [sdg] tag#6 uas_zap_pending 0 uas-tag 12 
inflight: CMD
[1971144.279037] sd 9:0:0:0: [sdg] tag#6 CDB: Write(10) 2a 00 28 ef a7 
38 00 04 00 00
[1971144.279041] sd 9:0:0:0: [sdg] tag#7 uas_zap_pending 0 uas-tag 13 
inflight: CMD
[1971144.279045] sd 9:0:0:0: [sdg] tag#7 CDB: Write(10) 2a 00 28 ef a3 
38 00 04 00 00
[1971144.279050] sd 9:0:0:0: [sdg] tag#8 uas_zap_pending 0 uas-tag 14 
inflight: CMD
[1971144.279054] sd 9:0:0:0: [sdg] tag#8 CDB: Write(10) 2a 00 28 ef 9b 
38 00 04 00 00
[1971144.279058] sd 9:0:0:0: [sdg] tag#9 uas_zap_pending 0 uas-tag 15 
inflight: CMD
[1971144.279062] sd 9:0:0:0: [sdg] tag#9 CDB: Write(10) 2a 00 28 ef 97 
38 00 04 00 00
[1971144.279067] sd 9:0:0:0: [sdg] tag#10 uas_zap_pending 0 uas-tag 16 
inflight: CMD
[1971144.279071] sd 9:0:0:0: [sdg] tag#10 CDB: Write(10) 2a 00 28 ef 93 
38 00 04 00 00
[1971144.279075] sd 9:0:0:0: [sdg] tag#11 uas_zap_pending 0 uas-tag 17 
inflight: CMD
[1971144.279079] sd 9:0:0:0: [sdg] tag#11 CDB: Write(10) 2a 00 28 ef 9f 
38 00 04 00 00
[1971144.279084] sd 9:0:0:0: [sdg] tag#12 uas_zap_pending 0 uas-tag 18 
inflight: CMD
[1971144.279088] sd 9:0:0:0: [sdg] tag#12 CDB: Write(10) 2a 00 28 ef e7 
38 00 04 00 00
[1971144.279092] sd 9:0:0:0: [sdg] tag#13 uas_zap_pending 0 uas-tag 19 
inflight: CMD
[1971144.279096] sd 9:0:0:0: [sdg] tag#13 CDB: Write(10) 2a 00 28 ef e3 
38 00 04 00 00
[1971144.279101] sd 9:0:0:0: [sdg] tag#14 uas_zap_pending 0 uas-tag 20 
inflight: CMD
[1971144.279104] sd 9:0:0:0: [sdg] tag#14 CDB: Write(10) 2a 00 28 ef df 
38 00 04 00 00
[1971144.279109] sd 9:0:0:0: [sdg] tag#15 uas_zap_pending 0 uas-tag 21 
inflight: CMD
[1971144.279112] sd 9:0:0:0: [sdg] tag#15 CDB: Write(10) 2a 00 28 ef db 
38 00 04 00 00
[1971144.279117] sd 9:0:0:0: [sdg] tag#17 uas_zap_pending 0 uas-tag 22 
inflight: CMD
[1971144.279120] sd 9:0:0:0: [sdg] tag#17 CDB: Write(10) 2a 00 28 ef d7 
38 00 04 00 00
[1971144.358097] usb 2-4.4.4.4.3: reset SuperSpeed Gen 1 USB device 
number 9 using xhci_hcd
[1971144.386970] scsi host9: uas_eh_device_reset_handler success
[1971144.406452] sd 9:0:0:0: [sdg] tag#20 data cmplt err -71 uas-tag 1 
inflight: CMD
[1971144.406457] sd 9:0:0:0: [sdg] tag#20 CDB: Write(10) 2a 00 28 ef bf 
38 00 04 00 00



On 15/09/2020 20:24, Alan Stern wrote:
> On Tue, Sep 15, 2020 at 06:43:49PM +0200, Patrik Nilsson wrote:
>> Hi Alan,
>>
>> Unfortunately the options you suggested didn't help.
>>
>> The patch below works and is tested with Ubuntu kernel 5.4.0.47.50.
>>
>> I have stress tested the usb system. To the USB is now seven mechanical hard
>> disks and two ssd disks connected. Six processes are at the same time
>> writing random data to the disks. One of them is to the ssd disk I couldn't
>> write data to before without it failed. Also the other usb-ssd disk is my
>> root partition.
>>
>> Before I applied the patch, my root partition sometimes failed to be kept
>> mounted. Now I have not had any crashes.
>>
>> This is a quick fix for hard disks, but working. It continued to work when I
>> started three virtualbox guests and let them also do work. The guests' hard
>> disks is on my usb-root partition.
>>
>> It doesn't work if I also use my usb2ethernet adapter (ID 2001:4a00 D-Link
>> Corp.), although my root partition and two randomize tests survived. Maybe a
>> much larger timeout in this case will help? But this I don't find as a good
>> solution.
>>
>> The behavior is the same on the other (much slower) computer with a
>> different usb hub. I have also tested it with exactly the same setup as
>> earlier, with no mechanical hard disks, and it works with the patch and not
>> without it.
> Is there any way you can capture a usbmon trace that shows the problem?
>
>> Any suggestion on how to solve this? In a good way.
>>
>> Best regards,
>> Patrik
>>
>> ---start of diff---
>> diff --git a/drivers/usb/core/hub.c b/drivers/usb/core/hub.c
>> index 5b768b80d1ee..3c550934815c 100644
>> --- a/drivers/usb/core/hub.c
>> +++ b/drivers/usb/core/hub.c
>> @@ -105,7 +105,7 @@ MODULE_PARM_DESC(use_both_schemes,
>>   DECLARE_RWSEM(ehci_cf_port_reset_rwsem);
>>   EXPORT_SYMBOL_GPL(ehci_cf_port_reset_rwsem);
>>
>> -#define HUB_DEBOUNCE_TIMEOUT    2000
>> +#define HUB_DEBOUNCE_TIMEOUT    10000
>>   #define HUB_DEBOUNCE_STEP      25
>>   #define HUB_DEBOUNCE_STABLE     100
>>
>> diff --git a/include/linux/usb.h b/include/linux/usb.h
>> index 20c555db4621..e64d441bb78f 100644
>> --- a/include/linux/usb.h
>> +++ b/include/linux/usb.h
>> @@ -1841,8 +1841,8 @@ extern int usb_set_configuration(struct usb_device
>> *dev, int configuration);
>>    * USB identifies 5 second timeouts, maybe more in a few cases, and a few
>>    * slow devices (like some MGE Ellipse UPSes) actually push that limit.
>>    */
>> -#define USB_CTRL_GET_TIMEOUT    5000
>> -#define USB_CTRL_SET_TIMEOUT    5000
>> +#define USB_CTRL_GET_TIMEOUT    10000
>> +#define USB_CTRL_SET_TIMEOUT    10000
> What happens if you leave these two lines unchanged and increase only
> the HUB_DEBOUNCE_TIMEOUT value?
>
> Alan Stern

Re: high speed data to usb disk makes the kernel think that is has been unmounted

[Alan Stern]

On Sat, Dec 05, 2020 at 12:49:45PM +0100, Patrik Nilsson wrote:
> Dear Alan,
> 
> After long time testing it seems that changing the TIMEOUT values did help
> somewhat, but not completely.
> 
> I tested two SSD and wrote to them at the same time:
> 
> # dd if=/dev/zero of=/dev/sdd bs=$((10*1024*1024))
> 
> # dd if=/dev/zero of=/dev/sdg bs=$((10*1024*1024)) # different bash terminal
> 
> The synchronize write gives a lot of errors as the log below (1) shows
> 
> Running big with blocks in synchronized mode gives the same kind of error:
> 
> # dd if=/dev/zero of=/dev/sdi bs=$((10*1024*1024)) oflag=dsync # this is
> another SSD bought for this test
> dd: error writing '/dev/sdi': Input/output error
> 9+0 records in
> 8+0 records out
> 83886080 bytes (84 MB, 80 MiB) copied, 185.585 s, 452 kB/s
> 
> When synchronized data transfer is done to sdi and sdg in a small block size
> everything works out as it should, but takes a long time.
> 
> # dd if=/dev/zero of=/dev/sdg bs=1024 oflag=dsync
> 56897100+0 records in
> 56897100+0 records out
> 58262630400 bytes (58 GB, 54 GiB) copied, 74235.8 s, 785 kB/s
> [...]
> dd: error writing '/dev/sdg': No space left on device
> 488386585+0 records in
> 488386584+0 records out
> 500107862016 bytes (500 GB, 466 GiB) copied, 702184 s, 712 kB/s
> 
> I have been unable to catch this kind of error leading to a crash.
> 
> The shutdown of the test computer did show the same kind of errors as the
> below log. It couldn't write asynchronous data.

Without more information it's impossible to tell what's really going
wrong.  The logs by themselves just aren't good enough.

Messing around with the timings isn't going to fix the real problem,
which is that you keep getting write errors.  Once a write error has
occurred you're already in trouble; failing to reinitialize the drive
afterward is minor in comparison.

The best thing for you to do at this point is collect a usbmon trace 
showing some errors as they occur.  Information on how to use usbmon is 
in the kernel source file Documentation/usb/usbmon.rst.

Alan Stern