i2c read timed out - Githubissues

nekinie commented 6 years ago

First off, thank you for taking the time to work on this project :)

I'm using a Lenovo Ideapad 530s with the AMD 2500U CPU. I'm running a fresh install of Arch Linux with the 4.18.16 kernel.

This project enables the touch pad to work for a short period of time before it stops.

dmesg output:

[ 2883.598041] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 3071.563513] INFO: task irq/65-MSFT0001:406 blocked for more than 120 seconds. [ 3071.563520] Tainted: G OE 4.18.16-arch1-1-ARCH #1 [ 3071.563523] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 3071.563526] irq/65-MSFT0001 D 0 406 2 0x80000080 [ 3071.563531] Call Trace: [ 3071.563543] ? schedule+0x29b/0x8b0 [ 3071.563548] ? rcu_preempt_qs+0x2b/0x50 [ 3071.563551] schedule+0x32/0x90 [ 3071.563555] schedule_preempt_disabled+0x14/0x20 [ 3071.563558] mutex_lock.isra.0+0x220/0x530 [ 3071.563564] ? switch_to_asm+0x40/0x70 [ 3071.563567] ? switch_to_asm+0x34/0x70 [ 3071.563570] ? switch_to_asm+0x34/0x70 [ 3071.563578] amd_mp2_read+0x4f/0x17b [i2c_amd_pci_mp2] [ 3071.563584] i2c_amd_xfer+0x61/0x150 [i2c_amd_plat_mp2] [ 3071.563590] ? irq_thread_dtor+0x80/0x80 [ 3071.563595] i2c_transfer+0x113/0x430 [ 3071.563599] ? _raw_spin_unlock_irq+0x1d/0x30 [ 3071.563602] ? irq_thread_dtor+0x80/0x80 [ 3071.563604] i2c_transfer+0x51/0xc0 [ 3071.563607] ? irq_forced_thread_fn+0x70/0x70 [ 3071.563610] i2c_transfer_buffer_flags+0x4c/0x70 [ 3071.563616] i2c_hid_irq+0x3c/0x130 [i2c_hid] [ 3071.563619] ? irq_forced_thread_fn+0x70/0x70 [ 3071.563622] irq_thread_fn+0x1f/0x50 [ 3071.563626] ? irq_forced_thread_fn+0x70/0x70 [ 3071.563628] irq_thread+0x142/0x1a0 [ 3071.563632] ? wake_threads_waitq+0x30/0x30 [ 3071.563637] kthread+0x112/0x130 [ 3071.563641] ? kthread_flush_work_fn+0x10/0x10 [ 3071.563645] ret_from_fork+0x22/0x40

Syniurge commented 6 years ago

Hi,

Strangely I've never hit this issue, which was also reported by another tester on the linux-i2c ML: https://marc.info/?l=linux-acpi&m=154102110306071&w=2, i.e neither my touchscreen or touchpad ever timed out during a read or write.

So not 100% sure but it should be fixed by 527c9bddafd24b2c47d568ab1bb5c52bef6ff908 I just pushed. Let me know if it does so I can submit a v8 to the kernel.

nekinie commented 6 years ago

Thanks for getting back!

Applied the changes and rebooted, touchpad no longer functions, new dmesg stuff too:

[ 13.490149] i2c_amd_plat_mp2 AMDI0011:01: i2c write timed out [ 13.490162] hid (null): reading report descriptor failed [ 13.490170] i2c_hid i2c-MSFT0001:00: can't add hid device: -5 [ 13.490378] i2c_hid: probe of i2c-MSFT0001:00 failed with error -5

Did a grep for i2c_amd_plat_mp2 but nothing else shows up :(

Syniurge commented 6 years ago

By request of one reviewer I merged the two modules into one named i2c-amd-mp2. Could you check if you still have the old modules i2c_amd_plat_mp2 and i2c_amd_pci_mp2 most likely in /lib/modules/..../updates/dkms? If yes delete them, and re-install the dkms module to run depmod and update the initramfs.

nekinie commented 6 years ago

Sorry my derp,

Things are looking better now:

[ 12.605496] i2c_amd_mp2: loading out-of-tree module taints kernel. [ 12.605530] i2c_amd_mp2: module verification failed: signature and/or required key missing - tainting kernel [ 12.606289] i2c_amd_mp2: AMD(R) PCI-E MP2 I2C Controller Driver Version: 1.0 [ 12.606293] i2c_amd_mp2 0000:03:00.7: MP2 device found [1022:15e6] (rev 0) [ 12.606485] i2c_amd_mp2 0000:03:00.7: MP2 device registered. [ 12.627366] tpm_crb MSFT0101:00: can't request region for resource [mem 0xbf7a6000-0xbf7a9fff] [ 12.628247] tpm_crb: probe of MSFT0101:00 failed with error -16

I'll monitor this for a few hours and report back it's status.

thank you :)

nekinie commented 6 years ago

so I have not seen anymore issues, everything it looking good :)

nekinie commented 6 years ago

May have jumped the gun a little,

My system is now usable which is awesome!

But every now and then the mouse freezes for a few seconds before continuing to function as normal. An i2c read timed out message is printed in dmesg each time this happens.

Syniurge commented 6 years ago

No matter what I try I can't get a time out on my machine, so I have a few questions:

Which kernel version are you on? Do you see only one timeout error or a pack of 3 every time (there should be 3 retries, and the timeout is set to 250ms)? Are you doing anything specific while these freezes occur? How often do they happen?

nekinie commented 6 years ago

They happen around once every 30 minutes, single line logged to dmesg, kernel 4.18.16

I have not noticed a pattern to the occurrence but will start noting the time and what I was doing when they happen.

They occur under general usage, browsing web, playing music, light coding. I'll include a full dmesg dump tomorrow after a full days work.

nekinie commented 6 years ago

First freeze of the day.

Interesting more than one i2c read time out logged.

g='unit=tlp-sleep comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4166.934075] audit: type=1130 audit(1541579451.463:84): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=man-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4166.934079] audit: type=1131 audit(1541579451.463:85): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=man-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4170.241257] wlp1s0: authenticate with 84:b8:02:45:9c:7c [ 4170.280445] wlp1s0: send auth to 84:b8:02:45:9c:7c (try 1/3) [ 4170.281152] wlp1s0: authenticated [ 4170.282464] wlp1s0: associate with 84:b8:02:45:9c:7c (try 1/3) [ 4170.285183] wlp1s0: RX AssocResp from 84:b8:02:45:9c:7c (capab=0x1111 status=0 aid=199) [ 4170.287604] wlp1s0: associated [ 4170.294546] wlp1s0: Limiting TX power to 8 dBm as advertised by 84:b8:02:45:9c:7c [ 4389.190391] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4429.196082] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4505.190838] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4521.938711] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4522.392089] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4549.195241] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4677.215607] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out

nekinie commented 6 years ago

And another one

g='unit=man-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4166.934079] audit: type=1131 audit(1541579451.463:85): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=man-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4170.241257] wlp1s0: authenticate with 84:b8:02:45:9c:7c [ 4170.280445] wlp1s0: send auth to 84:b8:02:45:9c:7c (try 1/3) [ 4170.281152] wlp1s0: authenticated [ 4170.282464] wlp1s0: associate with 84:b8:02:45:9c:7c (try 1/3) [ 4170.285183] wlp1s0: RX AssocResp from 84:b8:02:45:9c:7c (capab=0x1111 status=0 aid=199) [ 4170.287604] wlp1s0: associated [ 4170.294546] wlp1s0: Limiting TX power to 8 dBm as advertised by 84:b8:02:45:9c:7c [ 4389.190391] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4429.196082] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4505.190838] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4521.938711] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4522.392089] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4549.195241] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4677.215607] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4797.205319] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4812.672381] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4817.205980] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4821.205922] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4823.685970] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4825.179368] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4985.218640] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5017.222346] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5020.982828] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5021.302840] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5025.223289] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5521.179157] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5536.166390] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out

nekinie commented 6 years ago

and again, 2 lines logged this time

nekinie commented 6 years ago

So it looks like its still happening, but I'm not always using the mouse to detect it.

[ 4170.280445] wlp1s0: send auth to 84:b8:02:45:9c:7c (try 1/3) [ 4170.281152] wlp1s0: authenticated [ 4170.282464] wlp1s0: associate with 84:b8:02:45:9c:7c (try 1/3) [ 4170.285183] wlp1s0: RX AssocResp from 84:b8:02:45:9c:7c (capab=0x1111 status=0 aid=199) [ 4170.287604] wlp1s0: associated [ 4170.294546] wlp1s0: Limiting TX power to 8 dBm as advertised by 84:b8:02:45:9c:7c [ 4389.190391] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4429.196082] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4505.190838] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4521.938711] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4522.392089] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4549.195241] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4677.215607] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4797.205319] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4812.672381] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4817.205980] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4821.205922] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4823.685970] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4825.179368] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4985.218640] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5017.222346] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5020.982828] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5021.302840] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5025.223289] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5521.179157] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5536.166390] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5633.211349] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5648.812580] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5745.213329] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5849.209393] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5917.208461] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5956.701920] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6001.209190] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6009.502624] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6097.211860] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6157.213351] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6164.733637] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6193.215123] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6437.226053] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6485.228859] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6548.271926] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6665.985144] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6833.228320] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6856.266635] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 7097.215613] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 7117.215759] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 7133.215548] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 7201.188495] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out

nekinie commented 6 years ago

[ 0.000000] Linux version 4.18.16-arch1-1-ARCH (builduser@heftig-28760) (gcc version 8.2.1 20180831 (GCC)) #1 SMP PREEMPT Sat Oct 20 22:06:45 UTC 2018 [ 0.000000] Command line: initrd=\amd-ucode.img initrd=\initramfs-linux.img luks.uuid=5193e11d-8ffc-4b18-93ce-e4ceac3a0cea luks.name=5193e11d-8ffc-4b18-93ce-e4ceac3a0cea=cruptroot root=UUID=c20142b7-d078-421d-8743-b3e029dd4a3e rw [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] AMD AuthenticAMD [ 0.000000] Centaur CentaurHauls [ 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: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x0000000000086fff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000087000-0x0000000000087fff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000088000-0x000000000009ffff] usable [ 0.000000] BIOS-e820: [mem 0x00000000000a0000-0x00000000000bffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000009bfffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000009c00000-0x0000000009dfffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000009e00000-0x0000000009efffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000009f00000-0x0000000009f09fff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x0000000009f0a000-0x00000000a35a9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000a35aa000-0x00000000a3ea9fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000a3eaa000-0x00000000b4ebefff] usable [ 0.000000] BIOS-e820: [mem 0x00000000b4ebf000-0x00000000beebefff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000beebf000-0x00000000bf7befff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x00000000bf7bf000-0x00000000bf7fefff] ACPI data [ 0.000000] BIOS-e820: [mem 0x00000000bf7ff000-0x00000000bf7fffff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bf800000-0x00000000cfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000f8000000-0x00000000fbffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fec10000-0x00000000fec10fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fed80000-0x00000000fed80fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000ff800000-0x00000000fff4ffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000022f33ffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] e820: update [mem 0xa13e6018-0xa13f3457] usable ==> usable [ 0.000000] e820: update [mem 0xa13e6018-0xa13f3457] usable ==> usable [ 0.000000] extended physical RAM map: [ 0.000000] reserve setup_data: [mem 0x0000000000000000-0x0000000000086fff] usable [ 0.000000] reserve setup_data: [mem 0x0000000000087000-0x0000000000087fff] reserved [ 0.000000] reserve setup_data: [mem 0x0000000000088000-0x000000000009ffff] usable [ 0.000000] reserve setup_data: [mem 0x00000000000a0000-0x00000000000bffff] reserved [ 0.000000] reserve setup_data: [mem 0x0000000000100000-0x0000000009bfffff] usable [ 0.000000] reserve setup_data: [mem 0x0000000009c00000-0x0000000009dfffff] reserved [ 0.000000] reserve setup_data: [mem 0x0000000009e00000-0x0000000009efffff] usable [ 0.000000] reserve setup_data: [mem 0x0000000009f00000-0x0000000009f09fff] ACPI NVS [ 0.000000] reserve setup_data: [mem 0x0000000009f0a000-0x00000000a13e6017] usable [ 0.000000] reserve setup_data: [mem 0x00000000a13e6018-0x00000000a13f3457] usable [ 0.000000] reserve setup_data: [mem 0x00000000a13f3458-0x00000000a35a9fff] usable [ 0.000000] reserve setup_data: [mem 0x00000000a35aa000-0x00000000a3ea9fff] reserved [ 0.000000] reserve setup_data: [mem 0x00000000a3eaa000-0x00000000b4ebefff] usable [ 0.000000] reserve setup_data: [mem 0x00000000b4ebf000-0x00000000beebefff] reserved [ 0.000000] reserve setup_data: [mem 0x00000000beebf000-0x00000000bf7befff] ACPI NVS [ 0.000000] reserve setup_data: [mem 0x00000000bf7bf000-0x00000000bf7fefff] ACPI data [ 0.000000] reserve setup_data: [mem 0x00000000bf7ff000-0x00000000bf7fffff] usable [ 0.000000] reserve setup_data: [mem 0x00000000bf800000-0x00000000cfffffff] reserved [ 0.000000] reserve setup_data: [mem 0x00000000f8000000-0x00000000fbffffff] reserved [ 0.000000] reserve setup_data: [mem 0x00000000fec00000-0x00000000fec00fff] reserved [ 0.000000] reserve setup_data: [mem 0x00000000fec10000-0x00000000fec10fff] reserved [ 0.000000] reserve setup_data: [mem 0x00000000fed80000-0x00000000fed80fff] reserved [ 0.000000] reserve setup_data: [mem 0x00000000fee00000-0x00000000fee00fff] reserved [ 0.000000] reserve setup_data: [mem 0x00000000ff800000-0x00000000fff4ffff] reserved [ 0.000000] reserve setup_data: [mem 0x0000000100000000-0x000000022f33ffff] usable [ 0.000000] efi: EFI v2.60 by INSYDE Corp. [ 0.000000] efi: ACPI=0xbf7fe000 ACPI 2.0=0xbf7fe014 SMBIOS=0xb6b4a000 SMBIOS 3.0=0xb6b48000 ESRT=0xb6b4bb98 MEMATTR=0xab5d5418 TPMEventLog=0xa3eb9018 [ 0.000000] SMBIOS 3.1.1 present. [ 0.000000] DMI: LENOVO 81H1/LNVNB161216, BIOS 8PCN45WW 08/17/2018 [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] last_pfn = 0x22f340 max_arch_pfn = 0x400000000 [ 0.000000] MTRR default type: uncachable [ 0.000000] MTRR fixed ranges enabled: [ 0.000000] 00000-9FFFF write-back [ 0.000000] A0000-BFFFF uncachable [ 0.000000] C0000-DFFFF write-through [ 0.000000] E0000-FFFFF uncachable [ 0.000000] MTRR variable ranges enabled: [ 0.000000] 0 base 000000000000 mask FFFF80000000 write-back [ 0.000000] 1 base 000080000000 mask FFFFC0000000 write-back [ 0.000000] 2 base 0000C0000000 mask FFFFF0000000 write-back [ 0.000000] 3 base 0000FF800000 mask FFFFFF800000 write-protect [ 0.000000] 4 disabled [ 0.000000] 5 disabled [ 0.000000] 6 disabled [ 0.000000] 7 disabled [ 0.000000] TOM2: 0000000230000000 aka 8960M [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
[ 0.000000] last_pfn = 0xbf800 max_arch_pfn = 0x400000000 [ 0.000000] esrt: Reserving ESRT space from 0x00000000b6b4bb98 to 0x00000000b6b4bbd0. [ 0.000000] Scanning 1 areas for low memory corruption [ 0.000000] Base memory trampoline at [(ptrval)] 99000 size 24576 [ 0.000000] Using GB pages for direct mapping [ 0.000000] BRK [0x622ec000, 0x622ecfff] PGTABLE [ 0.000000] BRK [0x622ed000, 0x622edfff] PGTABLE [ 0.000000] BRK [0x622ee000, 0x622eefff] PGTABLE [ 0.000000] BRK [0x622ef000, 0x622effff] PGTABLE [ 0.000000] BRK [0x622f0000, 0x622f0fff] PGTABLE [ 0.000000] BRK [0x622f1000, 0x622f1fff] PGTABLE [ 0.000000] BRK [0x622f2000, 0x622f2fff] PGTABLE [ 0.000000] BRK [0x622f3000, 0x622f3fff] PGTABLE [ 0.000000] BRK [0x622f4000, 0x622f4fff] PGTABLE [ 0.000000] BRK [0x622f5000, 0x622f5fff] PGTABLE [ 0.000000] Secure boot disabled [ 0.000000] RAMDISK: [mem 0x7f3af000-0x7fffefff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000BF7FE014 000024 (v02 LENOVO) [ 0.000000] ACPI: XSDT 0x00000000BF7CD188 0000D4 (v01 LENOVO CB-01 00000001 01000013) [ 0.000000] ACPI: FACP 0x00000000BF7F3000 00010C (v05 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: DSDT 0x00000000BF7E9000 0063AF (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: FACS 0x00000000BF39B000 000040 [ 0.000000] ACPI: UEFI 0x00000000BF7FD000 000236 (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: SSDT 0x00000000BF7F7000 00533D (v02 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: MSDM 0x00000000BF7F6000 000055 (v03 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: ASF! 0x00000000BF7F5000 0000A5 (v32 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: BOOT 0x00000000BF7F4000 000028 (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: HPET 0x00000000BF7F2000 000038 (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: APIC 0x00000000BF7F1000 000138 (v03 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: MCFG 0x00000000BF7F0000 00003C (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: SSDT 0x00000000BF7E7000 00119C (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: CRAT 0x00000000BF7E6000 000810 (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: CDIT 0x00000000BF7E5000 000029 (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: UEFI 0x00000000BF7E4000 000042 (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: VFCT 0x00000000BF7D6000 00D484 (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: SSDT 0x00000000BF7D5000 00046D (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: TPM2 0x00000000BF7D4000 000034 (v04 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: IVRS 0x00000000BF7D3000 0000D0 (v02 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: SSDT 0x00000000BF7D2000 000AC1 (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: SSDT 0x00000000BF7D0000 001124 (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: SSDT 0x00000000BF7CE000 001A41 (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: FPDT 0x00000000BF7CC000 000044 (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: BGRT 0x00000000BF7CB000 000038 (v01 LENOVO CB-01 00000001 ACPI 00040000) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000022f33ffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x22f33c000-0x22f33ffff] [ 0.000000] tsc: Fast TSC calibration using PIT [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x000000022f33ffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x0000000000086fff] [ 0.000000] node 0: [mem 0x0000000000088000-0x000000000009ffff] [ 0.000000] node 0: [mem 0x0000000000100000-0x0000000009bfffff] [ 0.000000] node 0: [mem 0x0000000009e00000-0x0000000009efffff] [ 0.000000] node 0: [mem 0x0000000009f0a000-0x00000000a35a9fff] [ 0.000000] node 0: [mem 0x00000000a3eaa000-0x00000000b4ebefff] [ 0.000000] node 0: [mem 0x00000000bf7ff000-0x00000000bf7fffff] [ 0.000000] node 0: [mem 0x0000000100000000-0x000000022f33ffff] [ 0.000000] Reserved but unavailable: 48300 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000022f33ffff] [ 0.000000] On node 0 totalpages: 1980052 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 22 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] DMA32 zone: 11471 pages used for memmap [ 0.000000] DMA32 zone: 734134 pages, LIFO batch:31 [ 0.000000] Normal zone: 19405 pages used for memmap [ 0.000000] Normal zone: 1241920 pages, LIFO batch:31 [ 0.000000] ACPI: PM-Timer IO Port: 0x408 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x04] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x05] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x06] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x07] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x08] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x09] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x0a] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x0b] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x0c] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x0d] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x0e] high edge lint[0x1]) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x0f] high edge lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 4, version 33, address 0xfec00000, GSI 0-23 [ 0.000000] IOAPIC[1]: apic_id 5, version 33, address 0xfec01000, GSI 24-55 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level) [ 0.000000] ACPI: IRQ0 used by override. [ 0.000000] ACPI: IRQ9 used by override. [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x10228210 base: 0xfed00000 [ 0.000000] smpboot: Allowing 16 CPUs, 8 hotplug CPUs [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x00087000-0x00087fff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000bffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000c0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0x09c00000-0x09dfffff] [ 0.000000] PM: Registered nosave memory: [mem 0x09f00000-0x09f09fff] [ 0.000000] PM: Registered nosave memory: [mem 0xa13e6000-0xa13e6fff] [ 0.000000] PM: Registered nosave memory: [mem 0xa13f3000-0xa13f3fff] [ 0.000000] PM: Registered nosave memory: [mem 0xa35aa000-0xa3ea9fff] [ 0.000000] PM: Registered nosave memory: [mem 0xb4ebf000-0xbeebefff] [ 0.000000] PM: Registered nosave memory: [mem 0xbeebf000-0xbf7befff] [ 0.000000] PM: Registered nosave memory: [mem 0xbf7bf000-0xbf7fefff] [ 0.000000] PM: Registered nosave memory: [mem 0xbf800000-0xcfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xd0000000-0xf7ffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xf8000000-0xfbffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfc000000-0xfebfffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfec00000-0xfec00fff] [ 0.000000] PM: Registered nosave memory: [mem 0xfec01000-0xfec0ffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfec10000-0xfec10fff] [ 0.000000] PM: Registered nosave memory: [mem 0xfec11000-0xfed7ffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfed80000-0xfed80fff] [ 0.000000] PM: Registered nosave memory: [mem 0xfed81000-0xfedfffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfee00000-0xfee00fff] [ 0.000000] PM: Registered nosave memory: [mem 0xfee01000-0xff7fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff800000-0xfff4ffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfff50000-0xffffffff] [ 0.000000] [mem 0xd0000000-0xf7ffffff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on bare hardware [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370452778343963 ns [ 0.000000] random: get_random_bytes called from start_kernel+0x95/0x535 with crng_init=0 [ 0.000000] setup_percpu: NR_CPUS:320 nr_cpumask_bits:320 nr_cpu_ids:16 nr_node_ids:1 [ 0.000000] percpu: Embedded 46 pages/cpu @(ptrval) s151552 r8192 d28672 u262144 [ 0.000000] pcpu-alloc: s151552 r8192 d28672 u262144 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 00 01 02 03 04 05 06 07 [0] 08 09 10 11 12 13 14 15 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1949090 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: initrd=\amd-ucode.img initrd=\initramfs-linux.img luks.uuid=5193e11d-8ffc-4b18-93ce-e4ceac3a0cea luks.name=5193e11d-8ffc-4b18-93ce-e4ceac3a0cea=cruptroot root=UUID=c20142b7-d078-421d-8743-b3e029dd4a3e rw [ 0.000000] Calgary: detecting Calgary via BIOS EBDA area [ 0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing! [ 0.000000] Memory: 7624284K/7920208K available (12300K kernel code, 1417K rwdata, 3812K rodata, 1524K init, 644K bss, 295924K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=16, Nodes=1 [ 0.000000] ftrace: allocating 35854 entries in 141 pages [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] CONFIG_RCU_FANOUT set to non-default value of 32 [ 0.000000] RCU dyntick-idle grace-period acceleration is enabled. [ 0.000000] RCU restricting CPUs from NR_CPUS=320 to nr_cpu_ids=16. [ 0.000000] RCU priority boosting: priority 1 delay 500 ms. [ 0.000000] Tasks RCU enabled. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=16 [ 0.000000] NR_IRQS: 20736, nr_irqs: 1096, preallocated irqs: 16 [ 0.000000] Offload RCU callbacks from CPUs: (none). [ 0.000000] Console: colour dummy device 80x25 [ 0.000000] console [tty0] enabled [ 0.000000] ACPI: Core revision 20180531 [ 0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484873504 ns [ 0.000000] hpet clockevent registered [ 0.000000] APIC: Switch to symmetric I/O mode setup [ 0.000000] [Firmware Bug]: AMD-Vi: IOAPIC[4] not in IVRS table [ 0.000000] [Firmware Bug]: AMD-Vi: IOAPIC[5] not in IVRS table [ 0.000000] [Firmware Bug]: AMD-Vi: No southbridge IOAPIC found [ 0.000000] AMD-Vi: Disabling interrupt remapping [ 0.000000] Switched APIC routing to physical flat. [ 0.000000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.016666] tsc: Fast TSC calibration using PIT [ 0.019999] tsc: Detected 1996.114 MHz processor [ 0.019999] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x398baf2a45c, max_idle_ns: 881590654824 ns [ 0.019999] Calibrating delay loop (skipped), value calculated using timer frequency.. 3993.57 BogoMIPS (lpj=6653713) [ 0.019999] pid_max: default: 32768 minimum: 301 [ 0.027178] Security Framework initialized [ 0.027180] Yama: becoming mindful. [ 0.027184] AppArmor: AppArmor disabled by boot time parameter [ 0.031108] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes) [ 0.031875] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes) [ 0.032016] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes) [ 0.032038] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes) [ 0.033015] mce: CPU supports 23 MCE banks [ 0.033034] LVT offset 1 assigned for vector 0xf9 [ 0.033083] LVT offset 2 assigned for vector 0xf4 [ 0.033093] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 512 [ 0.033094] Last level dTLB entries: 4KB 1536, 2MB 1536, 4MB 768, 1GB 0 [ 0.033096] Spectre V2 : Mitigation: Full AMD retpoline [ 0.033096] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch [ 0.033097] Spectre V2 : Spectre v2 mitigation: Enabling Indirect Branch Prediction Barrier [ 0.033097] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp [ 0.037882] Freeing SMP alternatives memory: 32K [ 0.043333] smpboot: CPU0: AMD Ryzen 5 2500U with Radeon Vega Mobile Gfx (family: 0x17, model: 0x11, stepping: 0x0) [ 0.060020] Performance Events: Fam17h core perfctr, AMD PMU driver. [ 0.060029] ... version: 0 [ 0.060030] ... bit width: 48 [ 0.060031] ... generic registers: 6 [ 0.060033] ... value mask: 0000ffffffffffff [ 0.060034] ... max period: 00007fffffffffff [ 0.060035] ... fixed-purpose events: 0 [ 0.060036] ... event mask: 000000000000003f [ 0.066679] Hierarchical SRCU implementation. [ 0.090722] NMI watchdog: Enabled. Permanently consumes one hw-PMU counter. [ 0.096677] smp: Bringing up secondary CPUs ... [ 0.136709] x86: Booting SMP configuration: [ 0.136712] .... node #0, CPUs: #1 #2 #3 #4 #5 #6 #7 [ 0.379088] smp: Brought up 1 node, 8 CPUs [ 0.379088] smpboot: Max logical packages: 2 [ 0.379088] smpboot: Total of 8 processors activated (31950.56 BogoMIPS) [ 0.380445] devtmpfs: initialized [ 0.380445] x86/mm: Memory block size: 128MB [ 0.381454] PM: Registering ACPI NVS region [mem 0x09f00000-0x09f09fff] (40960 bytes) [ 0.381454] PM: Registering ACPI NVS region [mem 0xbeebf000-0xbf7befff] (9437184 bytes) [ 0.383431] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns [ 0.383431] futex hash table entries: 4096 (order: 6, 262144 bytes) [ 0.384647] pinctrl core: initialized pinctrl subsystem [ 0.384809] RTC time: 20:31:38, date: 11/06/18 [ 0.385589] NET: Registered protocol family 16 [ 0.385739] audit: initializing netlink subsys (disabled) [ 0.385745] audit: type=2000 audit(1541536297.383:1): state=initialized audit_enabled=0 res=1 [ 0.385745] cpuidle: using governor ladder [ 0.385745] cpuidle: using governor menu [ 0.386748] Simple Boot Flag at 0x44 set to 0x1 [ 0.386883] ACPI: bus type PCI registered [ 0.386885] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.387295] PCI: MMCONFIG for domain 0000 [bus 00-3f] at [mem 0xf8000000-0xfbffffff] (base 0xf8000000) [ 0.387305] PCI: MMCONFIG at [mem 0xf8000000-0xfbffffff] reserved in E820 [ 0.387305] PCI: Using configuration type 1 for base access [ 0.388114] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.388114] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.390338] ACPI: Added _OSI(Module Device) [ 0.390338] ACPI: Added _OSI(Processor Device) [ 0.390338] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.390338] ACPI: Added _OSI(Processor Aggregator Device) [ 0.390338] ACPI: Added _OSI(Linux-Dell-Video) [ 0.392168] ACPI: [Firmware Bug]: BIOS OSI(Linux) query ignored [ 0.400330] ACPI: 7 ACPI AML tables successfully acquired and loaded [ 0.405269] ACPI: EC: EC started [ 0.405270] ACPI: EC: interrupt blocked [ 0.405521] ACPI: _SB.PCI0.LPC0.EC0: Used as first EC [ 0.405523] ACPI: _SB.PCI0.LPC0.EC0_: GPE=0x3, EC_CMD/EC_SC=0x66, ECDATA=0x62 [ 0.405524] ACPI: _SB.PCI0.LPC0.EC0_: Used as boot DSDT EC to handle transactions [ 0.405525] ACPI: Interpreter enabled [ 0.405542] ACPI: (supports S0 S3 S4 S5) [ 0.405543] ACPI: Using IOAPIC for interrupt routing [ 0.405764] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.405970] ACPI: Enabled 2 GPEs in block 00 to 1F [ 0.408918] ACPI: Power Resource [P0ST] (on) [ 0.408950] ACPI: Power Resource [P3ST] (on) [ 0.412683] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.412688] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI] [ 0.412886] acpi PNP0A08:00: _OSC: platform does not support [SHPCHotplug LTR] [ 0.413059] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] [ 0.413071] acpi PNP0A08:00: [Firmware Info]: MMCONFIG for domain 0000 [bus 00-3f] only partially covers this bridge [ 0.413350] PCI host bridge to bus 0000:00 [ 0.413353] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.413354] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.413355] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.413357] pci_bus 0000:00: root bus resource [mem 0x000c0000-0x000c3fff window] [ 0.413358] pci_bus 0000:00: root bus resource [mem 0x000c4000-0x000c7fff window] [ 0.413359] pci_bus 0000:00: root bus resource [mem 0x000c8000-0x000cbfff window] [ 0.413360] pci_bus 0000:00: root bus resource [mem 0x000cc000-0x000cffff window] [ 0.413361] pci_bus 0000:00: root bus resource [mem 0x000d0000-0x000d3fff window] [ 0.413362] pci_bus 0000:00: root bus resource [mem 0x000d4000-0x000d7fff window] [ 0.413364] pci_bus 0000:00: root bus resource [mem 0x000d8000-0x000dbfff window] [ 0.413365] pci_bus 0000:00: root bus resource [mem 0x000dc000-0x000dffff window] [ 0.413366] pci_bus 0000:00: root bus resource [mem 0x000e0000-0x000e3fff window] [ 0.413367] pci_bus 0000:00: root bus resource [mem 0x000e4000-0x000e7fff window] [ 0.413368] pci_bus 0000:00: root bus resource [mem 0x000e8000-0x000ebfff window] [ 0.413369] pci_bus 0000:00: root bus resource [mem 0x000ec000-0x000effff window] [ 0.413370] pci_bus 0000:00: root bus resource [mem 0xd0000000-0xf7ffffff window] [ 0.413371] pci_bus 0000:00: root bus resource [mem 0xfc000000-0xfed3ffff window] [ 0.413373] pcibus 0000:00: root bus resource [bus 00-ff] [ 0.413383] pci 0000:00:00.0: [1022:15d0] type 00 class 0x060000 [ 0.413526] pci 0000:00:00.2: [1022:15d1] type 00 class 0x080600 [ 0.413714] pci 0000:00:01.0: [1022:1452] type 00 class 0x060000 [ 0.413845] pci 0000:00:01.2: [1022:15d3] type 01 class 0x060400 [ 0.413979] pci 0000:00:01.2: PME# supported from D0 D3hot D3cold [ 0.414151] pci 0000:00:01.7: [1022:15d3] type 01 class 0x060400 [ 0.414284] pci 0000:00:01.7: PME# supported from D0 D3hot D3cold [ 0.414436] pci 0000:00:08.0: [1022:1452] type 00 class 0x060000 [ 0.414562] pci 0000:00:08.1: [1022:15db] type 01 class 0x060400 [ 0.414617] pci 0000:00:08.1: enabling Extended Tags [ 0.414673] pci 0000:00:08.1: PME# supported from D0 D3hot D3cold [ 0.414840] pci 0000:00:14.0: [1022:790b] type 00 class 0x0c0500 [ 0.415032] pci 0000:00:14.3: [1022:790e] type 00 class 0x060100 [ 0.415228] pci 0000:00:18.0: [1022:15e8] type 00 class 0x060000 [ 0.415295] pci 0000:00:18.1: [1022:15e9] type 00 class 0x060000 [ 0.415374] pci 0000:00:18.2: [1022:15ea] type 00 class 0x060000 [ 0.415438] pci 0000:00:18.3: [1022:15eb] type 00 class 0x060000 [ 0.415502] pci 0000:00:18.4: [1022:15ec] type 00 class 0x060000 [ 0.415567] pci 0000:00:18.5: [1022:15ed] type 00 class 0x060000 [ 0.415659] pci 0000:00:18.6: [1022:15ee] type 00 class 0x060000 [ 0.415723] pci 0000:00:18.7: [1022:15ef] type 00 class 0x060000 [ 0.416120] pci 0000:01:00.0: [168c:0042] type 00 class 0x028000 [ 0.416413] pci 0000:01:00.0: reg 0x10: [mem 0xe0200000-0xe03fffff 64bit] [ 0.417647] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold [ 0.418664] pci 0000:00:01.2: PCI bridge to [bus 01] [ 0.418673] pci 0000:00:01.2: bridge window [mem 0xe0200000-0xe03fffff] [ 0.418790] pci 0000:02:00.0: [1c5c:1327] type 00 class 0x010802 [ 0.418827] pci 0000:02:00.0: reg 0x10: [mem 0xe0900000-0xe0903fff 64bit] [ 0.418949] pci 0000:02:00.0: supports D1 [ 0.418950] pci 0000:02:00.0: PME# supported from D0 D1 D3hot [ 0.419129] pci 0000:00:01.7: PCI bridge to [bus 02] [ 0.419137] pci 0000:00:01.7: bridge window [mem 0xe0900000-0xe09fffff] [ 0.419262] pci 0000:03:00.0: [1002:15dd] type 00 class 0x030000 [ 0.419309] pci 0000:03:00.0: reg 0x10: [mem 0xd0000000-0xdfffffff 64bit pref] [ 0.419328] pci 0000:03:00.0: reg 0x18: [mem 0xe0000000-0xe01fffff 64bit pref] [ 0.419341] pci 0000:03:00.0: reg 0x20: [io 0x1000-0x10ff] [ 0.419353] pci 0000:03:00.0: reg 0x24: [mem 0xe0800000-0xe087ffff] [ 0.419374] pci 0000:03:00.0: enabling Extended Tags [ 0.419386] pci 0000:03:00.0: BAR 0: assigned to efifb [ 0.419484] pci 0000:03:00.0: PME# supported from D1 D2 D3hot D3cold [ 0.419637] pci 0000:03:00.1: [1002:15de] type 00 class 0x040300 [ 0.419667] pci 0000:03:00.1: reg 0x10: [mem 0xe08c8000-0xe08cbfff] [ 0.419742] pci 0000:03:00.1: enabling Extended Tags [ 0.419810] pci 0000:03:00.1: PME# supported from D1 D2 D3hot D3cold [ 0.419910] pci 0000:03:00.2: [1022:15df] type 00 class 0x108000 [ 0.419962] pci 0000:03:00.2: reg 0x18: [mem 0xe0700000-0xe07fffff] [ 0.420022] pci 0000:03:00.2: reg 0x24: [mem 0xe08ce000-0xe08cffff] [ 0.420043] pci 0000:03:00.2: enabling Extended Tags [ 0.420216] pci 0000:03:00.3: [1022:15e0] type 00 class 0x0c0330 [ 0.420253] pci 0000:03:00.3: reg 0x10: [mem 0xe0600000-0xe06fffff 64bit] [ 0.420317] pci 0000:03:00.3: enabling Extended Tags [ 0.420392] pci 0000:03:00.3: PME# supported from D0 D3hot D3cold [ 0.420525] pci 0000:03:00.4: [1022:15e1] type 00 class 0x0c0330 [ 0.420562] pci 0000:03:00.4: reg 0x10: [mem 0xe0500000-0xe05fffff 64bit] [ 0.420625] pci 0000:03:00.4: enabling Extended Tags [ 0.420700] pci 0000:03:00.4: PME# supported from D0 D3hot D3cold [ 0.420819] pci 0000:03:00.5: [1022:15e2] type 00 class 0x048000 [ 0.420850] pci 0000:03:00.5: reg 0x10: [mem 0xe0880000-0xe08bffff] [ 0.420925] pci 0000:03:00.5: enabling Extended Tags [ 0.420993] pci 0000:03:00.5: PME# supported from D0 D3hot D3cold [ 0.421107] pci 0000:03:00.6: [1022:15e3] type 00 class 0x040300 [ 0.421138] pci 0000:03:00.6: reg 0x10: [mem 0xe08c0000-0xe08c7fff] [ 0.421212] pci 0000:03:00.6: enabling Extended Tags [ 0.421279] pci 0000:03:00.6: PME# supported from D0 D3hot D3cold [ 0.421378] pci 0000:03:00.7: [1022:15e6] type 00 class 0x000000 [ 0.421430] pci 0000:03:00.7: reg 0x18: [mem 0xe0400000-0xe04fffff] [ 0.421464] pci 0000:03:00.7: reg 0x24: [mem 0xe08cc000-0xe08cdfff] [ 0.421485] pci 0000:03:00.7: enabling Extended Tags [ 0.421771] pci 0000:00:08.1: PCI bridge to [bus 03] [ 0.421777] pci 0000:00:08.1: bridge window [io 0x1000-0x1fff] [ 0.421781] pci 0000:00:08.1: bridge window [mem 0xe0400000-0xe08fffff] [ 0.421787] pci 0000:00:08.1: bridge window [mem 0xd0000000-0xe01fffff 64bit pref] [ 0.422580] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 5 6 10 11) 0, disabled. [ 0.422675] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 5 6 10 11) 0, disabled. [ 0.422749] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 5 6 10 11) 0, disabled. [ 0.422842] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 5 6 10 11) 0, disabled. [ 0.422927] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 5 6 10 11) 0, disabled. [ 0.422995] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 5 6 10 11) 0, disabled. [ 0.423063] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 5 6 10 11) 0, disabled. [ 0.423131] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 5 6 10 11) 0, disabled. [ 0.423658] ACPI: EC: interrupt unblocked [ 0.423676] ACPI: EC: event unblocked [ 0.423692] ACPI: _SB.PCI0.LPC0.EC0_: GPE=0x3, EC_CMD/EC_SC=0x66, ECDATA=0x62 [ 0.423693] ACPI: _SB.PCI0.LPC0.EC0_: Used as boot DSDT EC to handle transactions and events [ 0.423774] pci 0000:03:00.0: vgaarb: VGA device added: decodes=io+mem,owns=none,locks=none [ 0.423774] pci 0000:03:00.0: vgaarb: bridge control possible [ 0.423774] pci 0000:03:00.0: vgaarb: setting as boot device [ 0.423774] vgaarb: loaded [ 0.423774] ACPI: bus type USB registered [ 0.423774] usbcore: registered new interface driver usbfs [ 0.423774] usbcore: registered new interface driver hub [ 0.426676] usbcore: registered new device driver usb [ 0.426676] pps_core: LinuxPPS API ver. 1 registered [ 0.426676] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti giometti@linux.it [ 0.426676] PTP clock support registered [ 0.426676] EDAC MC: Ver: 3.0.0 [ 0.426739] Registered efivars operations [ 0.430077] PCI: Using ACPI for IRQ routing [ 0.433052] PCI: pci_cache_line_size set to 64 bytes [ 0.433281] e820: reserve RAM buffer [mem 0x00087000-0x0008ffff] [ 0.433282] e820: reserve RAM buffer [mem 0x09c00000-0x0bffffff] [ 0.433283] e820: reserve RAM buffer [mem 0x09f00000-0x0bffffff] [ 0.433284] e820: reserve RAM buffer [mem 0xa13e6018-0xa3ffffff] [ 0.433285] e820: reserve RAM buffer [mem 0xa35aa000-0xa3ffffff] [ 0.433286] e820: reserve RAM buffer [mem 0xb4ebf000-0xb7ffffff] [ 0.433287] e820: reserve RAM buffer [mem 0xbf800000-0xbfffffff] [ 0.433288] e820: reserve RAM buffer [mem 0x22f340000-0x22fffffff] [ 0.433457] NetLabel: Initializing [ 0.433458] NetLabel: domain hash size = 128 [ 0.433459] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.433484] NetLabel: unlabeled traffic allowed by default [ 0.433488] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.433488] hpet0: 3 comparators, 32-bit 14.318180 MHz counter [ 0.437664] clocksource: Switched to clocksource tsc-early [ 0.454783] VFS: Disk quotas dquot_6.6.0 [ 0.454850] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.455012] pnp: PnP ACPI init [ 0.455211] system 00:00: [mem 0xfec00000-0xfec01fff] could not be reserved [ 0.455213] system 00:00: [mem 0xfee00000-0xfee00fff] has been reserved [ 0.455214] system 00:00: [mem 0xfd300000-0xfd3fffff] has been reserved [ 0.455219] system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) [ 0.455384] pnp 00:01: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.455405] pnp 00:02: Plug and Play ACPI device, IDs FUJ7401 PNP0303 (active) [ 0.455451] system 00:03: [io 0x0400-0x04cf] has been reserved [ 0.455453] system 00:03: [io 0x04d0-0x04d1] has been reserved [ 0.455454] system 00:03: [io 0x04d6] has been reserved [ 0.455455] system 00:03: [io 0x0c00-0x0c01] has been reserved [ 0.455456] system 00:03: [io 0x0c14] has been reserved [ 0.455457] system 00:03: [io 0x0c50-0x0c52] has been reserved [ 0.455458] system 00:03: [io 0x0c6c] has been reserved [ 0.455459] system 00:03: [io 0x0c6f] has been reserved [ 0.455461] system 00:03: [io 0x0cd0-0x0cdb] has been reserved [ 0.455463] system 00:03: Plug and Play ACPI device, IDs PNP0c02 (active) [ 0.455532] system 00:04: [mem 0x000e0000-0x000fffff] could not be reserved [ 0.455533] system 00:04: [mem 0xff800000-0xffffffff] could not be reserved [ 0.455536] system 00:04: Plug and Play ACPI device, IDs PNP0c01 (active) [ 0.455991] pnp: PnP ACPI: found 5 devices [ 0.465932] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.465968] pci 0000:00:01.2: PCI bridge to [bus 01] [ 0.465974] pci 0000:00:01.2: bridge window [mem 0xe0200000-0xe03fffff] [ 0.465984] pci 0000:00:01.7: PCI bridge to [bus 02] [ 0.465988] pci 0000:00:01.7: bridge window [mem 0xe0900000-0xe09fffff] [ 0.465998] pci 0000:00:08.1: PCI bridge to [bus 03] [ 0.466000] pci 0000:00:08.1: bridge window [io 0x1000-0x1fff] [ 0.466005] pci 0000:00:08.1: bridge window [mem 0xe0400000-0xe08fffff] [ 0.466008] pci 0000:00:08.1: bridge window [mem 0xd0000000-0xe01fffff 64bit pref] [ 0.466016] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.466017] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.466019] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.466020] pci_bus 0000:00: resource 7 [mem 0x000c0000-0x000c3fff window] [ 0.466021] pci_bus 0000:00: resource 8 [mem 0x000c4000-0x000c7fff window] [ 0.466022] pci_bus 0000:00: resource 9 [mem 0x000c8000-0x000cbfff window] [ 0.466023] pci_bus 0000:00: resource 10 [mem 0x000cc000-0x000cffff window] [ 0.466025] pci_bus 0000:00: resource 11 [mem 0x000d0000-0x000d3fff window] [ 0.466025] pci_bus 0000:00: resource 12 [mem 0x000d4000-0x000d7fff window] [ 0.466026] pci_bus 0000:00: resource 13 [mem 0x000d8000-0x000dbfff window] [ 0.466027] pci_bus 0000:00: resource 14 [mem 0x000dc000-0x000dffff window] [ 0.466028] pci_bus 0000:00: resource 15 [mem 0x000e0000-0x000e3fff window] [ 0.466029] pci_bus 0000:00: resource 16 [mem 0x000e4000-0x000e7fff window] [ 0.466030] pci_bus 0000:00: resource 17 [mem 0x000e8000-0x000ebfff window] [ 0.466031] pci_bus 0000:00: resource 18 [mem 0x000ec000-0x000effff window] [ 0.466032] pci_bus 0000:00: resource 19 [mem 0xd0000000-0xf7ffffff window] [ 0.466033] pci_bus 0000:00: resource 20 [mem 0xfc000000-0xfed3ffff window] [ 0.466034] pci_bus 0000:01: resource 1 [mem 0xe0200000-0xe03fffff] [ 0.466036] pci_bus 0000:02: resource 1 [mem 0xe0900000-0xe09fffff] [ 0.466037] pci_bus 0000:03: resource 0 [io 0x1000-0x1fff] [ 0.466038] pci_bus 0000:03: resource 1 [mem 0xe0400000-0xe08fffff] [ 0.466039] pci_bus 0000:03: resource 2 [mem 0xd0000000-0xe01fffff 64bit pref] [ 0.466355] NET: Registered protocol family 2 [ 0.466765] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes) [ 0.466787] TCP established hash table entries: 65536 (order: 7, 524288 bytes) [ 0.466882] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes) [ 0.467023] TCP: Hash tables configured (established 65536 bind 65536) [ 0.467087] UDP hash table entries: 4096 (order: 5, 131072 bytes) [ 0.467109] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes) [ 0.467253] NET: Registered protocol family 1 [ 0.467258] NET: Registered protocol family 44 [ 0.467384] pci 0000:03:00.1: Linked as a consumer to 0000:03:00.0 [ 0.471044] PCI: CLS 64 bytes, default 64 [ 0.471105] Unpacking initramfs... [ 0.620376] Freeing initrd memory: 12608K [ 0.939585] AMD-Vi: Unable to write to IOMMU perf counter. [ 0.939731] pci 0000:00:00.2: can't derive routing for PCI INT A [ 0.939732] pci 0000:00:00.2: PCI INT A: not connected [ 0.940393] iommu: Adding device 0000:00:01.0 to group 0 [ 0.940700] iommu: Adding device 0000:00:01.2 to group 1 [ 0.941002] iommu: Adding device 0000:00:01.7 to group 2 [ 0.941323] iommu: Adding device 0000:00:08.0 to group 3 [ 0.941607] iommu: Adding device 0000:00:08.1 to group 4 [ 0.941919] iommu: Adding device 0000:00:14.0 to group 5 [ 0.941942] iommu: Adding device 0000:00:14.3 to group 5 [ 0.942241] iommu: Adding device 0000:00:18.0 to group 6 [ 0.942287] iommu: Adding device 0000:00:18.1 to group 6 [ 0.942310] iommu: Adding device 0000:00:18.2 to group 6 [ 0.942332] iommu: Adding device 0000:00:18.3 to group 6 [ 0.942356] iommu: Adding device 0000:00:18.4 to group 6 [ 0.942378] iommu: Adding device 0000:00:18.5 to group 6 [ 0.942401] iommu: Adding device 0000:00:18.6 to group 6 [ 0.942423] iommu: Adding device 0000:00:18.7 to group 6 [ 0.942817] iommu: Adding device 0000:01:00.0 to group 7 [ 0.943145] iommu: Adding device 0000:02:00.0 to group 8 [ 0.943513] iommu: Adding device 0000:03:00.0 to group 9 [ 0.943653] iommu: Using direct mapping for device 0000:03:00.0 [ 0.943869] iommu: Adding device 0000:03:00.1 to group 10 [ 0.943911] iommu: Adding device 0000:03:00.2 to group 10 [ 0.943984] iommu: Adding device 0000:03:00.3 to group 10 [ 0.944027] iommu: Adding device 0000:03:00.4 to group 10 [ 0.944070] iommu: Adding device 0000:03:00.5 to group 10 [ 0.944117] iommu: Adding device 0000:03:00.6 to group 10 [ 0.944159] iommu: Adding device 0000:03:00.7 to group 10 [ 0.944479] AMD-Vi: Found IOMMU at 0000:00:00.2 cap 0x40 [ 0.944480] AMD-Vi: Extended features (0x4f77ef22294ada): [ 0.944481] PPR NX GT IA GA PC GA_vAPIC [ 0.944732] AMD-Vi: Lazy IO/TLB flushing enabled [ 0.945662] amd_uncore: AMD NB counters detected [ 0.945666] amd_uncore: AMD LLC counters detected [ 0.946142] Scanning for low memory corruption every 60 seconds [ 0.947156] Initialise system trusted keyrings [ 0.947282] Key type blacklist registered [ 0.947527] workingset: timestamp_bits=41 max_order=21 bucket_order=0 [ 0.949723] zbud: loaded [ 0.950052] pstore: using lz4hc compression [ 0.954587] Key type asymmetric registered [ 0.954589] Asymmetric key parser 'x509' registered [ 0.954681] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 0.954797] io scheduler noop registered [ 0.954798] io scheduler deadline registered [ 0.954889] io scheduler cfq registered (default) [ 0.954890] io scheduler mq-deadline registered [ 0.954891] io scheduler kyber registered [ 0.954970] io scheduler bfq registered [ 0.955803] pcieport 0000:00:01.2: AER enabled with IRQ 26 [ 0.955844] pcieport 0000:00:01.7: AER enabled with IRQ 27 [ 0.955857] pcieport 0000:00:01.2: Signaling PME with IRQ 26 [ 0.955868] pcieport 0000:00:01.7: Signaling PME with IRQ 27 [ 0.955887] pcieport 0000:00:08.1: Signaling PME with IRQ 28 [ 0.955929] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 0.956019] efifb: probing for efifb [ 0.956030] efifb: framebuffer at 0xd0000000, using 4800k, total 4800k [ 0.956031] efifb: mode is 1280x960x32, linelength=5120, pages=1 [ 0.956031] efifb: scrolling: redraw [ 0.956032] efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 [ 0.958204] Console: switching to colour frame buffer device 160x60 [ 0.960214] fb0: EFI VGA frame buffer device [ 0.960283] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input0 [ 0.960303] ACPI: Lid Switch [LID] [ 0.960342] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input1 [ 0.960347] ACPI: Power Button [PWRB] [ 0.960406] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 [ 0.960433] ACPI: Power Button [PWRF] [ 0.960538] ACPI: Video Device [VGA1] (multi-head: yes rom: no post: no) [ 0.960815] acpi device:04: registered as cooling_device0 [ 0.960866] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:03/LNXVIDEO:01/input/input3 [ 0.960894] ACPI: Video Device [VGA2] (multi-head: yes rom: no post: no) [ 0.961000] acpi device:0c: registered as cooling_device1 [ 0.961036] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:0b/LNXVIDEO:02/input/input4 [ 0.961074] Monitor-Mwait will be used to enter C-1 state [ 0.976069] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.978778] nvme nvme0: pci function 0000:02:00.0 [ 0.978919] VFIO - User Level meta-driver version: 0.3 [ 0.979043] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 0.979065] ehci-pci: EHCI PCI platform driver [ 0.979089] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 0.979091] ohci-pci: OHCI PCI platform driver [ 0.979098] uhci_hcd: USB Universal Host Controller Interface driver [ 0.979217] xhci_hcd 0000:03:00.3: xHCI Host Controller [ 0.979221] xhci_hcd 0000:03:00.3: new USB bus registered, assigned bus number 1 [ 0.979449] xhci_hcd 0000:03:00.3: hcc params 0x0270ffe5 hci version 0x110 quirks 0x0000000040000410 [ 0.980006] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.18 [ 0.980009] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 0.980011] usb usb1: Product: xHCI Host Controller [ 0.980013] usb usb1: Manufacturer: Linux 4.18.16-arch1-1-ARCH xhci-hcd [ 0.980015] usb usb1: SerialNumber: 0000:03:00.3 [ 0.980313] hub 1-0:1.0: USB hub found [ 0.980343] hub 1-0:1.0: 4 ports detected [ 0.980840] xhci_hcd 0000:03:00.3: xHCI Host Controller [ 0.980845] xhci_hcd 0000:03:00.3: new USB bus registered, assigned bus number 2 [ 0.980849] xhci_hcd 0000:03:00.3: Host supports USB 3.10 Enhanced SuperSpeed [ 0.980935] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 0.980992] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.18 [ 0.980995] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 0.980997] usb usb2: Product: xHCI Host Controller [ 0.980998] usb usb2: Manufacturer: Linux 4.18.16-arch1-1-ARCH xhci-hcd [ 0.981000] usb usb2: SerialNumber: 0000:03:00.3 [ 0.981234] hub 2-0:1.0: USB hub found [ 0.981272] hub 2-0:1.0: 4 ports detected [ 0.982029] xhci_hcd 0000:03:00.4: xHCI Host Controller [ 0.982034] xhci_hcd 0000:03:00.4: new USB bus registered, assigned bus number 3 [ 0.982225] xhci_hcd 0000:03:00.4: hcc params 0x0260ffe5 hci version 0x110 quirks 0x0000000040000410 [ 0.982872] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.18 [ 0.982875] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 0.982877] usb usb3: Product: xHCI Host Controller [ 0.982879] usb usb3: Manufacturer: Linux 4.18.16-arch1-1-ARCH xhci-hcd [ 0.982881] usb usb3: SerialNumber: 0000:03:00.4 [ 0.983060] hub 3-0:1.0: USB hub found [ 0.983097] hub 3-0:1.0: 2 ports detected [ 0.983411] xhci_hcd 0000:03:00.4: xHCI Host Controller [ 0.983415] xhci_hcd 0000:03:00.4: new USB bus registered, assigned bus number 4 [ 0.983420] xhci_hcd 0000:03:00.4: Host supports USB 3.10 Enhanced SuperSpeed [ 0.983463] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM. [ 0.983493] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.18 [ 0.983495] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 0.983497] usb usb4: Product: xHCI Host Controller [ 0.983499] usb usb4: Manufacturer: Linux 4.18.16-arch1-1-ARCH xhci-hcd [ 0.983501] usb usb4: SerialNumber: 0000:03:00.4 [ 0.983637] hub 4-0:1.0: USB hub found [ 0.983649] hub 4-0:1.0: 1 port detected [ 0.983831] usbcore: registered new interface driver usbserial_generic [ 0.983836] usbserial: USB Serial support registered for generic [ 0.983873] rtc_cmos 00:01: RTC can wake from S4 [ 0.984150] rtc_cmos 00:01: registered as rtc0 [ 0.984180] rtc_cmos 00:01: alarms up to one month, 114 bytes nvram, hpet irqs [ 0.984345] ledtrig-cpu: registered to indicate activity on CPUs [ 0.984374] hidraw: raw HID events driver (C) Jiri Kosina [ 0.984426] usbcore: registered new interface driver usbhid [ 0.984427] usbhid: USB HID core driver [ 0.985119] NET: Registered protocol family 10 [ 0.990255] Segment Routing with IPv6 [ 0.990278] NET: Registered protocol family 17 [ 0.991175] RAS: Correctable Errors collector initialized. [ 0.992026] microcode: CPU0: patch_level=0x0810100b [ 0.992040] microcode: CPU1: patch_level=0x0810100b [ 0.992049] microcode: CPU2: patch_level=0x0810100b [ 0.992054] microcode: CPU3: patch_level=0x0810100b [ 0.992064] microcode: CPU4: patch_level=0x0810100b [ 0.992071] microcode: CPU5: patch_level=0x0810100b [ 0.992074] microcode: CPU6: patch_level=0x0810100b [ 0.992082] microcode: CPU7: patch_level=0x0810100b [ 0.992165] microcode: Microcode Update Driver: v2.2. [ 0.992205] sched_clock: Marking stable (992159721, 0)->(1077561923, -85402202) [ 0.992495] registered taskstats version 1 [ 0.992524] Loading compiled-in X.509 certificates [ 0.995063] Loaded X.509 cert 'Build time autogenerated kernel key: 893889fb80fa5763e18fe33b17bd4671d83b79c3' [ 0.995111] zswap: loaded using pool lzo/zbud [ 0.999048] Key type big_key registered [ 1.000044] Magic number: 10:82:548 [ 1.000187] rtc_cmos 00:01: setting system clock to 2018-11-06 20:31:38 UTC (1541536298) [ 1.195780] nvme0n1: p1 p2 [ 1.310062] usb 1-4: new full-speed USB device number 2 using xhci_hcd [ 1.313343] usb 3-2: new high-speed USB device number 2 using xhci_hcd [ 1.436317] Freeing unused kernel image memory: 1524K [ 1.436319] Write protecting the kernel read-only data: 18432k [ 1.438214] Freeing unused kernel image memory: 2008K [ 1.438302] Freeing unused kernel image memory: 284K [ 1.438465] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 1.456850] systemd[1]: systemd 239 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) [ 1.468053] usb 3-2: New USB device found, idVendor=13d3, idProduct=56b2, bcdDevice=17.11 [ 1.468056] usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 [ 1.468058] usb 3-2: Product: Integrated Camera [ 1.468059] usb 3-2: Manufacturer: SunplusIT Inc [ 1.470066] usb 1-4: New USB device found, idVendor=0cf3, idProduct=e500, bcdDevice= 0.01 [ 1.470068] usb 1-4: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 1.473726] systemd[1]: Detected architecture x86-64. [ 1.473729] systemd[1]: Running in initial RAM disk. [ 1.481240] random: systemd: uninitialized urandom read (16 bytes read) [ 1.481273] systemd[1]: Initializing machine ID from random generator. [ 1.498873] random: systemd: uninitialized urandom read (16 bytes read) [ 1.498979] systemd[1]: Listening on Journal Socket (/dev/log). [ 1.499141] random: systemd: uninitialized urandom read (16 bytes read) [ 1.499177] systemd[1]: Reached target Swap. [ 1.499304] systemd[1]: Reached target Timers. [ 1.501579] systemd[1]: Created slice system-systemd\x2dcryptsetup.slice. [ 1.501759] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 1.501886] systemd[1]: Reached target Paths. [ 1.532681] audit: type=1130 audit(1541536299.026:2): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udevd comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 1.564880] audit: type=1130 audit(1541536299.059:3): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 1.579354] audit: type=1130 audit(1541536299.073:4): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udev-trigger comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 1.585890] i8042: PNP: PS/2 Controller [PNP0303:KBC0] at 0x60,0x64 irq 1 [ 1.585892] i8042: PNP: PS/2 appears to have AUX port disabled, if this is incorrect please boot with i8042.nopnp [ 1.587241] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 1.592811] cryptd: max_cpu_qlen set to 1000 [ 1.600711] AVX2 version of gcm_enc/dec engaged. [ 1.600713] AES CTR mode by8 optimization enabled [ 1.614668] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input5 [ 1.648601] device-mapper: uevent: version 1.0.3 [ 1.648834] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com [ 1.654404] audit: type=1130 audit(1541536299.149:5): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-ask-password-console comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 1.970399] tsc: Refined TSC clocksource calibration: 1996.237 MHz [ 1.970420] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x398c9735f96, max_idle_ns: 881590446725 ns [ 1.970483] clocksource: Switched to clocksource tsc [ 11.125421] random: crng init done [ 11.125426] random: 7 urandom warning(s) missed due to ratelimiting [ 19.329761] NET: Registered protocol family 38 [ 19.454065] audit: type=1130 audit(1541536316.950:6): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-cryptsetup@cruptroot comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 19.486916] audit: type=1130 audit(1541536316.983:7): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-fsck-root comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 19.534483] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null) [ 19.597271] audit: type=1130 audit(1541536317.093:8): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=initrd-parse-etc comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 19.597277] audit: type=1131 audit(1541536317.093:9): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=initrd-parse-etc comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 19.611310] audit: type=1130 audit(1541536317.106:10): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udev-trigger comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 19.611313] audit: type=1131 audit(1541536317.106:11): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udev-trigger comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 19.613292] audit: type=1130 audit(1541536317.106:12): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-modules-load comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 19.613301] audit: type=1131 audit(1541536317.106:13): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-modules-load comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 19.617554] audit: type=1130 audit(1541536317.113:14): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-ask-password-console comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 19.617557] audit: type=1131 audit(1541536317.113:15): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-ask-password-console comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success' [ 19.719245] systemd-journald[176]: Received SIGTERM from PID 1 (systemd). [ 19.730032] systemd: 18 output lines suppressed due to ratelimiting [ 19.805467] systemd[1]: systemd 239 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) [ 19.820803] systemd[1]: Detected architecture x86-64. [ 19.825089] systemd[1]: Set hostname to . [ 19.928809] systemd[1]: Stopped Switch Root. [ 19.929793] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 19.929830] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 19.929842] systemd[1]: Stopped Journal Service. [ 19.931636] systemd[1]: Starting Journal Service... [ 19.932466] systemd[1]: Listening on Device-mapper event daemon FIFOs. [ 19.933310] systemd[1]: Stopped target Switch Root. [ 20.010548] systemd-journald[333]: Received request to flush runtime journal from PID 1 [ 20.113844] acpi_cpufreq: overriding BIOS provided _PSD data [ 20.118411] i2c_amd_mp2: loading out-of-tree module taints kernel. [ 20.118457] i2c_amd_mp2: module verification failed: signature and/or required key missing - tainting kernel [ 20.118993] i2c_amd_mp2: AMD(R) PCI-E MP2 I2C Controller Driver Version: 1.0 [ 20.118997] i2c_amd_mp2 0000:03:00.7: MP2 device found [1022:15e6] (rev 0) [ 20.119356] i2c_amd_mp2 0000:03:00.7: MP2 device registered. [ 20.166251] piix4_smbus 0000:00:14.0: SMBus Host Controller at 0xb00, revision 0 [ 20.166254] piix4_smbus 0000:00:14.0: Using register 0x02 for SMBus port selection [ 20.169366] sp5100_tco: SP5100/SB800 TCO WatchDog Timer Driver [ 20.169473] sp5100-tco sp5100-tco: Using 0xfeb00000 for watchdog MMIO address [ 20.169659] sp5100-tco sp5100-tco: initialized. heartbeat=60 sec (nowayout=0) [ 20.169916] tpm_crb MSFT0101:00: can't request region for resource [mem 0xbf7a6000-0xbf7a9fff] [ 20.171093] tpm_crb: probe of MSFT0101:00 failed with error -16 [ 20.178090] input: PC Speaker as /devices/platform/pcspkr/input/input6 [ 20.220842] ACPI: AC Adapter [ADP0] (off-line) [ 20.247075] Linux agpgart interface v0.103 [ 20.247483] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 20.260394] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 20.261705] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 [ 20.261709] cfg80211: failed to load regulatory.db [ 20.283583] kvm: Nested Virtualization enabled [ 20.283589] kvm: Nested Paging enabled [ 20.283590] SVM: Virtual VMLOAD VMSAVE supported [ 20.283591] SVM: Virtual GIF supported [ 20.287672] MCE: In-kernel MCE decoding enabled. [ 20.294002] EDAC amd64: Node 0: DRAM ECC disabled. [ 20.294005] EDAC amd64: ECC disabled in the BIOS or no ECC capability, module will not load. Either enable ECC checking or force module loading by setting 'ecc_enable_override'. (Note that use of the override may cause unknown side effects.) [ 20.304144] snd_hda_intel 0000:03:00.1: Handle vga_switcheroo audio client [ 20.316563] ACPI: Battery Slot [BAT0] (battery present) [ 20.317437] input: Ideapad extra buttons as /devices/pci0000:00/0000:00:14.3/PNP0C09:00/VPC2004:00/input/input7 [ 20.332759] input: HD-Audio Generic HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:08.1/0000:03:00.1/sound/card0/input8 [ 20.371493] i2c_hid i2c-MSFT0001:00: i2c-MSFT0001:00 supply vdd not found, using dummy regulator [ 20.376592] snd_hda_codec_realtek hdaudioC1D0: autoconfig for ALC236: line_outs=1 (0x14/0x0/0x0/0x0/0x0) type:speaker [ 20.376596] snd_hda_codec_realtek hdaudioC1D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 20.376599] snd_hda_codec_realtek hdaudioC1D0: hp_outs=1 (0x21/0x0/0x0/0x0/0x0) [ 20.376601] snd_hda_codec_realtek hdaudioC1D0: mono: mono_out=0x0 [ 20.376603] snd_hda_codec_realtek hdaudioC1D0: inputs: [ 20.376605] snd_hda_codec_realtek hdaudioC1D0: Mic=0x19 [ 20.376607] snd_hda_codec_realtek hdaudioC1D0: Internal Mic=0x12 [ 20.405320] input: MSFT0001:00 04F3:304B Mouse as /devices/platform/AMDI0011:01/i2c-6/i2c-MSFT0001:00/0018:04F3:304B.0001/input/input9 [ 20.405496] input: MSFT0001:00 04F3:304B Touchpad as /devices/platform/AMDI0011:01/i2c-6/i2c-MSFT0001:00/0018:04F3:304B.0001/input/input10 [ 20.405643] hid-generic 0018:04F3:304B.0001: input,hidraw0: I2C HID v1.00 Mouse [MSFT0001:00 04F3:304B] on i2c-MSFT0001:00 [ 20.415873] ath10k_pci 0000:01:00.0: pci irq msi oper_irq_mode 2 irq_mode 0 reset_mode 0 [ 20.476851] input: MSFT0001:00 04F3:304B Touchpad as /devices/platform/AMDI0011:01/i2c-6/i2c-MSFT0001:00/0018:04F3:304B.0001/input/input12 [ 20.476966] hid-multitouch 0018:04F3:304B.0001: input,hidraw0: I2C HID v1.00 Mouse [MSFT0001:00 04F3:304B] on i2c-MSFT0001:00 [ 20.486900] mousedev: PS/2 mouse device common for all mice [ 20.538198] pcieport 0000:00:01.2: AER: Corrected error received: 0000:00:01.0 [ 20.538211] pcieport 0000:00:01.2: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Receiver ID) [ 20.539368] pcieport 0000:00:01.2: device [1022:15d3] error status/mask=00000040/00006000 [ 20.540452] pcieport 0000:00:01.2: [ 6] Bad TLP
[ 20.571804] pcieport 0000:00:01.2: AER: Corrected error received: 0000:00:01.0 [ 20.571837] pcieport 0000:00:01.2: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Receiver ID) [ 20.572832] pcieport 0000:00:01.2: device [1022:15d3] error status/mask=00000040/00006000 [ 20.573951] pcieport 0000:00:01.2: [ 6] Bad TLP
[ 20.661088] [drm] amdgpu kernel modesetting enabled. [ 20.667000] AMD IOMMUv2 driver by Joerg Roedel jroedel@suse.de [ 20.675154] ath10k_pci 0000:01:00.0: qca9377 hw1.1 target 0x05020001 chip_id 0x003821ff sub 17aa:0901 [ 20.675157] ath10k_pci 0000:01:00.0: kconfig debug 1 debugfs 1 tracing 1 dfs 0 testmode 0 [ 20.675659] ath10k_pci 0000:01:00.0: firmware ver WLAN.TF.2.1-00021-QCARMSWP-1 api 6 features wowlan,ignore-otp crc32 42e41877 [ 20.685448] Parsing CRAT table with 1 nodes [ 20.685470] Creating topology SYSFS entries [ 20.685534] Topology: Add APU node [0x0:0x0] [ 20.685534] Finished initializing topology [ 20.685594] kfd kfd: Initialized module [ 20.686066] checking generic (d0000000 4b0000) vs hw (d0000000 10000000) [ 20.686068] fb: switching to amdgpudrmfb from EFI VGA [ 20.686142] Console: switching to colour dummy device 80x25 [ 20.686662] [drm] initializing kernel modesetting (RAVEN 0x1002:0x15DD 0x17AA:0x39FE 0xC4). [ 20.686700] [drm] register mmio base: 0xE0800000 [ 20.686701] [drm] register mmio size: 524288 [ 20.686741] [drm] probing gen 2 caps for device 1022:15db = 700d03/e [ 20.686744] [drm] probing mlw for device 1022:15db = 700d03 [ 20.686749] [drm] add ip block number 0 [ 20.686750] [drm] add ip block number 1 [ 20.686751] [drm] add ip block number 2 [ 20.686752] [drm] add ip block number 3 [ 20.686754] [drm] add ip block number 4 [ 20.686755] [drm] add ip block number 5 [ 20.686757] [drm] add ip block number 6 [ 20.686758] [drm] add ip block number 7 [ 20.686759] [drm] add ip block number 8 [ 20.687273] kfd kfd: DID 15dd is missing in supported_devices [ 20.687275] kfd kfd: kgd2kfd_probe failed [ 20.687309] [drm] VCN decode is enabled in VM mode [ 20.687309] [drm] VCN encode is enabled in VM mode [ 20.687332] ATOM BIOS: 113-RAVEN-107 [ 20.687380] [drm] vm size is 262144 GB, 4 levels, block size is 9-bit, fragment size is 9-bit [ 20.687398] amdgpu 0000:03:00.0: VRAM: 256M 0x000000F400000000 - 0x000000F40FFFFFFF (256M used) [ 20.687400] amdgpu 0000:03:00.0: GTT: 1024M 0x000000F500000000 - 0x000000F53FFFFFFF [ 20.687414] [drm] Detected VRAM RAM=256M, BAR=256M [ 20.687415] [drm] RAM width 128bits DDR4 [ 20.688618] [TTM] Zone kernel: Available graphics memory: 3883672 kiB [ 20.688619] [TTM] Zone dma32: Available graphics memory: 2097152 kiB [ 20.688621] [TTM] Initializing pool allocator [ 20.688627] [TTM] Initializing DMA pool allocator [ 20.688696] [drm] amdgpu: 256M of VRAM memory ready [ 20.688698] [drm] amdgpu: 3072M of GTT memory ready. [ 20.688713] [drm] GART: num cpu pages 262144, num gpu pages 262144 [ 20.689340] [drm] PCIE GART of 1024M enabled (table at 0x000000F400900000). [ 20.696413] [drm] use_doorbell being set to: [true] [ 20.697642] [drm] Found VCN firmware Version: 1.73 Family ID: 18 [ 20.697649] [drm] PSP loading VCN firmware [ 20.741934] ath10k_pci 0000:01:00.0: board_file api 2 bmi_id N/A crc32 8aedfa4a [ 20.980676] amdgpu: [powerplay] dpm has been enabled [ 20.981168] [drm:construct [amdgpu]] ERROR construct: Invalid Connector ObjectID from Adapter Service for connector index:2! type 0 expected 3 [ 20.981279] [drm:construct [amdgpu]] ERROR construct: Invalid Connector ObjectID from Adapter Service for connector index:3! type 0 expected 3 [ 20.985325] [drm] Display Core initialized with v3.1.44! [ 21.040810] [drm] SADs count is: -2, don't need to read it [ 21.041589] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 21.041591] [drm] Driver supports precise vblank timestamp query. [ 21.065471] [drm] VCN decode and encode initialized successfully. [ 21.068513] [drm] fb mappable at 0xC1100000 [ 21.068515] [drm] vram apper at 0xC0000000 [ 21.068517] [drm] size 8294400 [ 21.068518] [drm] fb depth is 24 [ 21.068519] [drm] pitch is 7680 [ 21.068692] fbcon: amdgpudrmfb (fb0) is primary device [ 21.085286] Console: switching to colour frame buffer device 240x67 [ 21.109224] amdgpu 0000:03:00.0: fb0: amdgpudrmfb frame buffer device [ 21.130495] amdgpu 0000:03:00.0: ring 0(gfx) uses VM inv eng 4 on hub 0 [ 21.130499] amdgpu 0000:03:00.0: ring 1(comp_1.0.0) uses VM inv eng 5 on hub 0 [ 21.130502] amdgpu 0000:03:00.0: ring 2(comp_1.1.0) uses VM inv eng 6 on hub 0 [ 21.130505] amdgpu 0000:03:00.0: ring 3(comp_1.2.0) uses VM inv eng 7 on hub 0 [ 21.130507] amdgpu 0000:03:00.0: ring 4(comp_1.3.0) uses VM inv eng 8 on hub 0 [ 21.130510] amdgpu 0000:03:00.0: ring 5(comp_1.0.1) uses VM inv eng 9 on hub 0 [ 21.130513] amdgpu 0000:03:00.0: ring 6(comp_1.1.1) uses VM inv eng 10 on hub 0 [ 21.130515] amdgpu 0000:03:00.0: ring 7(comp_1.2.1) uses VM inv eng 11 on hub 0 [ 21.130518] amdgpu 0000:03:00.0: ring 8(comp_1.3.1) uses VM inv eng 12 on hub 0 [ 21.130520] amdgpu 0000:03:00.0: ring 9(kiq_2.1.0) uses VM inv eng 13 on hub 0 [ 21.130524] amdgpu 0000:03:00.0: ring 10(sdma0) uses VM inv eng 4 on hub 1 [ 21.130527] amdgpu 0000:03:00.0: ring 11(vcn_dec) uses VM inv eng 5 on hub 1 [ 21.130529] amdgpu 0000:03:00.0: ring 12(vcn_enc0) uses VM inv eng 6 on hub 1 [ 21.130532] amdgpu 0000:03:00.0: ring 13(vcn_enc1) uses VM inv eng 7 on hub 1 [ 21.137480] [drm] Initialized amdgpu 3.26.0 20150101 for 0000:03:00.0 on minor 0 [ 21.256288] media: Linux media interface: v0.10 [ 21.276167] videodev: Linux video capture interface: v2.00 [ 21.301198] Bluetooth: Core ver 2.22 [ 21.301234] NET: Registered protocol family 31 [ 21.301236] Bluetooth: HCI device and connection manager initialized [ 21.301283] Bluetooth: HCI socket layer initialized [ 21.301311] Bluetooth: L2CAP socket layer initialized [ 21.301321] Bluetooth: SCO socket layer initialized [ 21.346667] ath10k_pci 0000:01:00.0: Unknown eventid: 118809 [ 21.349518] ath10k_pci 0000:01:00.0: Unknown eventid: 90118 [ 21.350619] ath10k_pci 0000:01:00.0: htt-ver 3.56 wmi-op 4 htt-op 3 cal otp max-sta 32 raw 0 hwcrypto 1 [ 21.356925] ath: EEPROM regdomain: 0x6c [ 21.356926] ath: EEPROM indicates we should expect a direct regpair map [ 21.356929] ath: Country alpha2 being used: 00 [ 21.356929] ath: Regpair used: 0x6c [ 21.366558] ath10k_pci 0000:01:00.0 wlp1s0: renamed from wlan0 [ 21.427902] uvcvideo: Found UVC 1.00 device Integrated Camera (13d3:56b2) [ 21.437180] input: HDA Digital PCBeep as /devices/pci0000:00/0000:00:08.1/0000:03:00.6/sound/card1/input13 [ 21.437508] input: HD-Audio Generic Mic as /devices/pci0000:00/0000:00:08.1/0000:03:00.6/sound/card1/input14 [ 21.437620] input: HD-Audio Generic Headphone as /devices/pci0000:00/0000:00:08.1/0000:03:00.6/sound/card1/input15 [ 21.442855] usbcore: registered new interface driver btusb [ 21.445811] uvcvideo 3-2:1.0: Entity type for entity Extension 4 was not initialized! [ 21.445815] uvcvideo 3-2:1.0: Entity type for entity Extension 3 was not initialized! [ 21.445817] uvcvideo 3-2:1.0: Entity type for entity Processing 2 was not initialized! [ 21.445819] uvcvideo 3-2:1.0: Entity type for entity Camera 1 was not initialized! [ 21.446016] input: Integrated Camera: Integrated C as /devices/pci0000:00/0000:00:08.1/0000:03:00.4/usb3/3-2/3-2:1.0/input/input16 [ 21.446142] usbcore: registered new interface driver uvcvideo [ 21.446143] USB Video Class driver (1.1.1) [ 21.483314] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 21.483316] Bluetooth: BNEP filters: protocol multicast [ 21.483322] Bluetooth: BNEP socket layer initialized [ 38.312883] kauditd_printk_skb: 47 callbacks suppressed [ 38.312885] audit: type=1006 audit(1541536335.806:63): pid=755 uid=0 old-auid=4294967295 auid=1000 tty=tty1 old-ses=4294967295 ses=1 res=1 [ 38.326086] audit: type=1130 audit(1541536335.820:64): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@1000 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 38.337341] audit: type=1006 audit(1541536335.833:65): pid=767 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=2 res=1 [ 38.393872] audit: type=1130 audit(1541536335.890:66): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@1000 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 51.744945] audit: type=1131 audit(1541536349.240:67): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 62.666592] pcieport 0000:00:01.2: AER: Corrected error received: 0000:00:01.0 [ 62.666618] pcieport 0000:00:01.2: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Receiver ID) [ 62.666745] pcieport 0000:00:01.2: device [1022:15d3] error status/mask=00000040/00006000 [ 62.666824] pcieport 0000:00:01.2: [ 6] Bad TLP
[ 63.423191] ath10k_pci 0000:01:00.0: Unknown eventid: 118809 [ 63.426034] ath10k_pci 0000:01:00.0: Unknown eventid: 90118 [ 63.440587] IPv6: ADDRCONF(NETDEV_UP): wlp1s0: link is not ready [ 68.341493] wlp1s0: authenticate with 78:8a:20:db:33:c6 [ 68.373284] wlp1s0: send auth to 78:8a:20:db:33:c6 (try 1/3) [ 68.374835] wlp1s0: authenticated [ 68.376685] wlp1s0: associate with 78:8a:20:db:33:c6 (try 1/3) [ 68.380332] wlp1s0: RX AssocResp from 78:8a:20:db:33:c6 (capab=0x411 status=0 aid=4) [ 68.383236] wlp1s0: associated [ 68.402283] IPv6: ADDRCONF(NETDEV_CHANGE): wlp1s0: link becomes ready [ 83.202646] fuse init (API version 7.27) [ 94.540954] audit: type=1130 audit(1541536392.036:68): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=rtkit-daemon comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 94.645587] audit: type=1130 audit(1541536392.140:69): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=polkit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 95.946821] Bluetooth: RFCOMM TTY layer initialized [ 95.946853] Bluetooth: RFCOMM socket layer initialized [ 95.946865] Bluetooth: RFCOMM ver 1.11 [ 110.251464] audit: type=1130 audit(1541536407.746:70): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=tlp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 173.940618] wlp1s0: deauthenticating from 78:8a:20:db:33:c6 by local choice (Reason: 3=DEAUTH_LEAVING) [ 174.161268] audit: type=1131 audit(1541536471.656:71): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=netctl-auto@wlp1s0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 174.195371] pcieport 0000:00:01.2: AER: Multiple Corrected error received: 0000:00:01.0 [ 174.195387] pcieport 0000:00:01.2: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Receiver ID) [ 174.195394] pcieport 0000:00:01.2: device [1022:15d3] error status/mask=00000040/00006000 [ 174.195400] pcieport 0000:00:01.2: [ 6] Bad TLP
[ 174.952693] ath10k_pci 0000:01:00.0: Unknown eventid: 118809 [ 174.955544] ath10k_pci 0000:01:00.0: Unknown eventid: 90118 [ 174.969713] IPv6: ADDRCONF(NETDEV_UP): wlp1s0: link is not ready [ 175.021735] audit: type=1130 audit(1541536472.516:72): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=netctl-auto@wlp1s0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 179.934608] wlp1s0: authenticate with 78:8a:20:db:33:c6 [ 179.965385] wlp1s0: send auth to 78:8a:20:db:33:c6 (try 1/3) [ 179.967322] wlp1s0: authenticated [ 179.970013] wlp1s0: associate with 78:8a:20:db:33:c6 (try 1/3) [ 179.973995] wlp1s0: RX AssocResp from 78:8a:20:db:33:c6 (capab=0x411 status=0 aid=4) [ 179.976996] wlp1s0: associated [ 179.981947] IPv6: ADDRCONF(NETDEV_CHANGE): wlp1s0: link becomes ready [ 180.028589] 8021q: 802.1Q VLAN Support v1.8 [ 716.664504] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 924.042764] audit: type=1130 audit(1541537221.565:73): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 924.042771] audit: type=1131 audit(1541537221.565:74): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 1060.667955] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 1062.745714] audit: type=1130 audit(1541537360.268:75): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=upower comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 1088.664626] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 1100.664664] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 1112.664623] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 1556.668140] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 2344.670986] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 3848.691285] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4163.705013] audit: type=1130 audit(1541540461.197:76): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=tlp-sleep comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4163.716771] PM: suspend entry (deep) [ 4163.716774] PM: Syncing filesystems ... done. [ 4163.719735] Freezing user space processes ... (elapsed 0.002 seconds) done. [ 4163.721967] OOM killer disabled. [ 4163.721968] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done. [ 4163.723509] Suspending console(s) (use no_console_suspend to debug) [ 4163.723985] wlp1s0: deauthenticating from 78:8a:20:db:33:c6 by local choice (Reason: 3=DEAUTH_LEAVING) [ 4163.938626] amdgpu 0000:03:00.0: 00000000e0981f80 unpin not necessary [ 4164.034192] ACPI: EC: interrupt blocked [ 4164.066602] ACPI: Preparing to enter system sleep state S3 [ 4164.067571] ACPI: EC: event blocked [ 4164.067572] ACPI: EC: EC stopped [ 4164.067573] PM: Saving platform NVS memory [ 4164.067721] Disabling non-boot CPUs ... [ 4164.111544] smpboot: CPU 1 is now offline [ 4164.161679] smpboot: CPU 2 is now offline [ 4164.201377] smpboot: CPU 3 is now offline [ 4164.255298] smpboot: CPU 4 is now offline [ 4164.295046] smpboot: CPU 5 is now offline [ 4164.338321] smpboot: CPU 6 is now offline [ 4164.391766] smpboot: CPU 7 is now offline [ 4164.394746] ACPI: Low-level resume complete [ 4164.394802] ACPI: EC: EC started [ 4164.394803] PM: Restoring platform NVS memory [ 4164.467307] Enabling non-boot CPUs ... [ 4164.467349] x86: Booting SMP configuration: [ 4164.467350] smpboot: Booting Node 0 Processor 1 APIC 0x1 [ 4164.469928] cache: parent cpu1 should not be sleeping [ 4164.470016] microcode: CPU1: patch_level=0x0810100b [ 4164.470121] CPU1 is up [ 4164.470152] smpboot: Booting Node 0 Processor 2 APIC 0x2 [ 4164.472891] cache: parent cpu2 should not be sleeping [ 4164.473009] microcode: CPU2: patch_level=0x0810100b [ 4164.473142] CPU2 is up [ 4164.473156] smpboot: Booting Node 0 Processor 3 APIC 0x3 [ 4164.475898] cache: parent cpu3 should not be sleeping [ 4164.476007] microcode: CPU3: patch_level=0x0810100b [ 4164.476177] CPU3 is up [ 4164.476198] smpboot: Booting Node 0 Processor 4 APIC 0x4 [ 4164.479498] cache: parent cpu4 should not be sleeping [ 4164.479653] microcode: CPU4: patch_level=0x0810100b [ 4164.479830] CPU4 is up [ 4164.479851] smpboot: Booting Node 0 Processor 5 APIC 0x5 [ 4164.482484] cache: parent cpu5 should not be sleeping [ 4164.482662] microcode: CPU5: patch_level=0x0810100b [ 4164.482881] CPU5 is up [ 4164.482903] smpboot: Booting Node 0 Processor 6 APIC 0x6 [ 4164.485571] cache: parent cpu6 should not be sleeping [ 4164.485762] microcode: CPU6: patch_level=0x0810100b [ 4164.485968] CPU6 is up [ 4164.485980] smpboot: Booting Node 0 Processor 7 APIC 0x7 [ 4164.488611] cache: parent cpu7 should not be sleeping [ 4164.488797] microcode: CPU7: patch_level=0x0810100b [ 4164.489070] CPU7 is up [ 4164.489966] ACPI: Waking up from system sleep state S3 [ 4164.490794] ACPI: EC: interrupt unblocked [ 4164.540259] ACPI: EC: event unblocked [ 4164.542210] [drm] PCIE GART of 1024M enabled (table at 0x000000F400900000). [ 4164.542243] [drm] PSP is resuming... [ 4164.771575] usb 1-4: reset full-speed USB device number 2 using xhci_hcd [ 4164.780544] usb 3-2: reset high-speed USB device number 2 using xhci_hcd [ 4164.819977] amdgpu: [powerplay] dpm has been enabled [ 4165.030535] [drm] VCN decode and encode initialized successfully. [ 4165.030560] amdgpu 0000:03:00.0: ring 0(gfx) uses VM inv eng 4 on hub 0 [ 4165.030561] amdgpu 0000:03:00.0: ring 1(comp_1.0.0) uses VM inv eng 5 on hub 0 [ 4165.030562] amdgpu 0000:03:00.0: ring 2(comp_1.1.0) uses VM inv eng 6 on hub 0 [ 4165.030564] amdgpu 0000:03:00.0: ring 3(comp_1.2.0) uses VM inv eng 7 on hub 0 [ 4165.030565] amdgpu 0000:03:00.0: ring 4(comp_1.3.0) uses VM inv eng 8 on hub 0 [ 4165.030566] amdgpu 0000:03:00.0: ring 5(comp_1.0.1) uses VM inv eng 9 on hub 0 [ 4165.030567] amdgpu 0000:03:00.0: ring 6(comp_1.1.1) uses VM inv eng 10 on hub 0 [ 4165.030568] amdgpu 0000:03:00.0: ring 7(comp_1.2.1) uses VM inv eng 11 on hub 0 [ 4165.030569] amdgpu 0000:03:00.0: ring 8(comp_1.3.1) uses VM inv eng 12 on hub 0 [ 4165.030570] amdgpu 0000:03:00.0: ring 9(kiq_2.1.0) uses VM inv eng 13 on hub 0 [ 4165.030572] amdgpu 0000:03:00.0: ring 10(sdma0) uses VM inv eng 4 on hub 1 [ 4165.030573] amdgpu 0000:03:00.0: ring 11(vcn_dec) uses VM inv eng 5 on hub 1 [ 4165.030574] amdgpu 0000:03:00.0: ring 12(vcn_enc0) uses VM inv eng 6 on hub 1 [ 4165.030575] amdgpu 0000:03:00.0: ring 13(vcn_enc1) uses VM inv eng 7 on hub 1 [ 4165.277501] ath10k_pci 0000:01:00.0: Unknown eventid: 118809 [ 4165.280333] ath10k_pci 0000:01:00.0: Unknown eventid: 90118 [ 4165.298538] acpi LNXPOWER:01: Turning OFF [ 4165.298598] OOM killer enabled. [ 4165.298599] Restarting tasks ... done. [ 4165.305947] audit: type=1130 audit(1541579449.836:77): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=shadow comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4165.396014] PM: suspend exit [ 4165.400728] audit: type=1130 audit(1541579449.929:78): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4165.400744] audit: type=1131 audit(1541579449.929:79): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4165.403902] audit: type=1131 audit(1541579449.933:80): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=shadow comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4165.503505] audit: type=1130 audit(1541579450.033:81): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=logrotate comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4165.503510] audit: type=1131 audit(1541579450.033:82): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=logrotate comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4165.694546] audit: type=1131 audit(1541579450.223:83): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=tlp-sleep comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4166.934075] audit: type=1130 audit(1541579451.463:84): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=man-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4166.934079] audit: type=1131 audit(1541579451.463:85): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=man-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 4170.241257] wlp1s0: authenticate with 84:b8:02:45:9c:7c [ 4170.280445] wlp1s0: send auth to 84:b8:02:45:9c:7c (try 1/3) [ 4170.281152] wlp1s0: authenticated [ 4170.282464] wlp1s0: associate with 84:b8:02:45:9c:7c (try 1/3) [ 4170.285183] wlp1s0: RX AssocResp from 84:b8:02:45:9c:7c (capab=0x1111 status=0 aid=199) [ 4170.287604] wlp1s0: associated [ 4170.294546] wlp1s0: Limiting TX power to 8 dBm as advertised by 84:b8:02:45:9c:7c [ 4389.190391] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4429.196082] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4505.190838] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4521.938711] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4522.392089] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4549.195241] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4677.215607] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4797.205319] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4812.672381] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4817.205980] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4821.205922] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4823.685970] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4825.179368] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 4985.218640] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5017.222346] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5020.982828] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5021.302840] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5025.223289] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5521.179157] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5536.166390] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5633.211349] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5648.812580] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5745.213329] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5849.209393] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5917.208461] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 5956.701920] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6001.209190] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6009.502624] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6097.211860] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6157.213351] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6164.733637] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6193.215123] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6437.226053] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6485.228859] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6548.271926] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6665.985144] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6833.228320] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 6856.266635] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 7097.215613] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 7117.215759] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 7133.215548] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out [ 7201.188495] i2c_amd_plat_mp2 AMDI0011:01: i2c read timed out

nekinie commented 6 years ago

This might not be directly related to this driver, audio drops out at the same time, this might be a deeper issue with my system.

Syniurge commented 6 years ago

I pushed a possible fix in the tentative-timeout-recovery branch, if it's due to the interrupt not getting picked up.

Also I should have asked, how many seconds last the freezes? Or is it less than a second? I could add some debug message if you aren't sure.

nekinie commented 6 years ago

Not 100% sure, somewhere between 1 and 3 seconds.

Deploying the branch now!

nekinie commented 6 years ago

Applied the changes, 30 mins all ok thus far.

nekinie commented 6 years ago

Happened again, single line logged.

[ 1605.741174] i2c_amd_mp2 AMDI0011:01: i2c read timed out

nekinie commented 6 years ago

and another, also single line logged.

[ 1724.732202] i2c_amd_mp2 AMDI0011:01: i2c read timed out

nekinie commented 6 years ago

Though I didn't notice the 2nd one, I think the mouse is being affected less but needs more testing to confirm.

nekinie commented 6 years ago

2 more but again, I did not notice them.

[ 1605.741174] i2c_amd_mp2 AMDI0011:01: i2c read timed out [ 1724.732202] i2c_amd_mp2 AMDI0011:01: i2c read timed out [ 1980.730824] i2c_amd_mp2 AMDI0011:01: i2c read timed out [ 2056.891441] i2c_amd_mp2 AMDI0011:01: i2c read timed out

nekinie commented 6 years ago

there's been 2 more, one not noticeable, 1 that felt a lot like the pauses I experienced with the master version.

nekinie commented 6 years ago

I moved over to the arch testing repos which brought an amd ucode update and a kernel update to 1.19.1. I also applied the kernel param pcie_aspm=off to fix the PCIE errors.

No more mouse issues.

I also have the following dmesg:

[ 0.174342] [Firmware Bug]: AMD-Vi: IOAPIC[4] not in IVRS table [ 0.174346] [Firmware Bug]: AMD-Vi: IOAPIC[5] not in IVRS table [ 0.174348] [Firmware Bug]: AMD-Vi: No southbridge IOAPIC found [ 0.174350] AMD-Vi: Disabling interrupt remapping

If I set the following kernel params ivrs_ioapic[4]=00:14.0 ivrs_ioapic[5]=00:00.2 then these warnings go away but I loose the mouse.

I'm leaning towards the BIOS from lenovo being buggy.

I wouldn't let this hold up getting your patches into the kernel.

Syniurge commented 6 years ago

That's great news! You seem to have the IOAPIC managing the MP2 interrupt (irq 31 on my Yoga 530) properly detected:

[ 0.000000] IOAPIC[0]: apic_id 4, version 33, address 0xfec00000, GSI 0-23
[ 0.000000] IOAPIC[1]: apic_id 5, version 33, address 0xfec01000, GSI
24-55  # probably covers your MP2

So I don't think these errors (also showing up on my Yoga 530) affect the MP2 controller.

If the timeouts were fixed by the amd microcode update then the problem was very likely due to interrupts getting emitted properly but not reaching/being handled properly by the CPU, and that also means that the driver is fine.

You haven't seen a timeout since the update?

nekinie commented 6 years ago

If the laptop sleeps and wakes up then everything goes crazy and the mouse will freeze allot. After a clean boot, everything is pretty stable, I occasionally see a timeout but its now multiple hours between triggers.

Syniurge commented 6 years ago

I tried suspending and waking up my Yoga 530 on a 4.19 kernel about 30 times, and couldn't get a timeout, a freeze or an error. One time my input did get into some trouble, I couldn't click but not with the USB mouse either, so it was probably X related.

I'm leaning towards something wonky happening with your interrupts. Are you getting other oddities after a resume or just the mouse? Does everything work fine on Windows?

splav commented 6 years ago

Hello. First of all thank you for your work. I'm using the latest version (v10) on IdeaPad 530s and unfortunately I have similar freezes accompanied with triple i2c read timed out message in dmesg. Arch 4.19.1-arch1-1-ARCH kernel.

The current cure - suspend with keyboard and resume. Helps each time.

If I can collect more detailed debug info - please give instructions. I have a limited experience with kernel debugging - helped to identify nilfs bug.

splav commented 6 years ago

A quick add: the touchpad freezes more often at two-finger scroll attempts. Right now it freezes at each two-finger touches. (Maybe the messages size > 32 bytes? Just guessing.)

JanValiska commented 6 years ago

Hello. I have same problem. Touchpad freeze.

i2c_amd_mp2 AMDI0011:01: i2c read timed out

kernel: 4.19.1-arch1-1-ARCH #1 SMP PREEMPT laptop: Lenovo ideapad 530s 14ARR (same BIOS as Yoga 530S)

I cannot determine when this timeouts occurs. It is unpredictable from my view. But there was about 3-4 freezes while i write this message.

If i can help by posting some more information, please give instructions.

Syniurge commented 6 years ago

Could you paste the contents of /proc/interrupts?

JanValiska commented 6 years ago

$ cat /proc/interrupts 
            CPU0       CPU1       CPU2       CPU3       CPU4       CPU5       CPU6       CPU7       
   0:         42          0          0          0          0          0          0          0   IO-APIC    2-edge      timer
   1:      63523          0          0          0          0          0          0          0   IO-APIC    1-edge      i8042
   6:          0          0          0          0          0          0          0          0   IO-APIC    6-edge      AMDI0010:00
   7:     485894          0     164170          0          0          0          0          0   IO-APIC    7-fasteoi   pinctrl_amd
   8:          0          0          0          0          0          0          0          1   IO-APIC    8-edge      rtc0
   9:        326        185          0          0          0          0          0          0   IO-APIC    9-fasteoi   acpi
  25:          0          0          0          0          0          0          0          0   PCI-MSI 4096-edge      AMD-Vi
  26:          0          0          0          0          0          0          0          0   PCI-MSI 20480-edge      aerdrv, PCIe PME
  27:          0          0          0          0          0          0          0          0   PCI-MSI 30720-edge      aerdrv, PCIe PME
  28:          0          0          0          0          0          0          0          0   PCI-MSI 133120-edge      PCIe PME
  30:          0        129          0          0          0          0          0          0   PCI-MSI 1585152-edge      snd_hda_intel:card1
  31:         36          0          0          0          0          0          0          0   PCI-MSI 1048576-edge      nvme0q0
  32:      33276          0          0          0          0          0          0          0   PCI-MSI 1048577-edge      nvme0q1
  33:          0       6384          0          0          0          0          0          0   PCI-MSI 1048578-edge      nvme0q2
  34:          0          0      32021          0          0          0          0          0   PCI-MSI 1048579-edge      nvme0q3
  35:          0          0          0       6891          0          0          0          0   PCI-MSI 1048580-edge      nvme0q4
  36:          0          0          0          0      27353          0          0          0   PCI-MSI 1048581-edge      nvme0q5
  37:          0          0          0          0          0       8642          0          0   PCI-MSI 1048582-edge      nvme0q6
  38:          0          0          0          0          0          0      30120          0   PCI-MSI 1048583-edge      nvme0q7
  39:          0          0          0          0          0          0          0       6617   PCI-MSI 1048584-edge      nvme0q8
  40:          0          0          0          0          0          0          0          0   PCI-MSI 1048585-edge      nvme0q9
  41:          0          0          0          0          0          0          0          0   PCI-MSI 1048586-edge      nvme0q10
  42:          0          0          0          0          0          0          0          0   PCI-MSI 1048587-edge      nvme0q11
  43:          0          0          0          0          0          0          0          0   PCI-MSI 1048588-edge      nvme0q12
  44:          0          0          0          0          0          0          0          0   PCI-MSI 1048589-edge      nvme0q13
  45:          0          0          0          0          0          0          0          0   PCI-MSI 1048590-edge      nvme0q14
  46:          0          0          0          0          0          0          0          0   PCI-MSI 1048591-edge      nvme0q15
  47:     375667     164208          0     110297          0          0          0          0   IO-APIC   31-fasteoi   0000:03:00.7
  48:        596        129          0          0          0          0          0          0   PCI-MSI 1579008-edge      xhci_hcd
  49:          0          0          0          0          0          0          0          0   PCI-MSI 1579009-edge      xhci_hcd
  50:          0          0          0          0          0          0          0          0   PCI-MSI 1579010-edge      xhci_hcd
  51:          0          0          0          0          0          0          0          0   PCI-MSI 1579011-edge      xhci_hcd
  52:          0          0          0          0          0          0          0          0   PCI-MSI 1579012-edge      xhci_hcd
  53:          0          0          0          0          0          0          0          0   PCI-MSI 1579013-edge      xhci_hcd
  54:          0          0          0          0          0          0          0          0   PCI-MSI 1579014-edge      xhci_hcd
  55:          0          0          0          0          0          0          0          0   PCI-MSI 1579015-edge      xhci_hcd
  57:         99         48          0          0          0          0          0          0   PCI-MSI 1581056-edge      xhci_hcd
  58:          0          0          0          0          0          0          0          0   PCI-MSI 1581057-edge      xhci_hcd
  59:          0          0          0          0          0          0          0          0   PCI-MSI 1581058-edge      xhci_hcd
  60:          0          0          0          0          0          0          0          0   PCI-MSI 1581059-edge      xhci_hcd
  61:          0          0          0          0          0          0          0          0   PCI-MSI 1581060-edge      xhci_hcd
  62:          0          0          0          0          0          0          0          0   PCI-MSI 1581061-edge      xhci_hcd
  63:          0          0          0          0          0          0          0          0   PCI-MSI 1581062-edge      xhci_hcd
  64:          0          0          0          0          0          0          0          0   PCI-MSI 1581063-edge      xhci_hcd
  66:          0          0          0          0          0          0          0          0   PCI-MSI 1048592-edge      nvme0q16
  68:          0          0         10          0          0          0          0          0   PCI-MSI 1574912-edge      snd_hda_intel:card0
  69:     485894          0     164169          0          0          0          0          0  amd_gpio   89  MSFT0001:00
  71:     557872          0          0          0          0     336952          0          0   PCI-MSI 524288-edge      ath10k_pci
  72:    1604572          0          0          0          0          0     524078          0   PCI-MSI 1572864-edge      amdgpu
 NMI:         25         71        107         67        110         67        109         65   Non-maskable interrupts
 LOC:    2002014     864871    1395942     778187    1349072     655822    1368252     600869   Local timer interrupts
 SPU:          0          0          0          0          0          0          0          0   Spurious interrupts
 PMI:         25         71        107         67        110         67        109         65   Performance monitoring interrupts
 IWI:          9          0          0          0          0          5          2          0   IRQ work interrupts
 RTR:          0          0          0          0          0          0          0          0   APIC ICR read retries
 RES:    2195925    2077676    2510123    1627401    2313519    1350131    2233079    1405005   Rescheduling interrupts
 CAL:     298307     224598     292773     223269     310440     227023     275931     225451   Function call interrupts
 TLB:     275525     211219     280216     209766     298194     213776     263263     212735   TLB shootdowns
 TRM:          0          0          0          0          0          0          0          0   Thermal event interrupts
 THR:          0          0          0          0          0          0          0          0   Threshold APIC interrupts
 DFR:          0          0          0          0          0          0          0          0   Deferred Error APIC interrupts
 MCE:          0          0          0          0          0          0          0          0   Machine check exceptions
 MCP:         85         83         83         84         84         84         84         84   Machine check polls
 HYP:          0          0          0          0          0          0          0          0   Hypervisor callback interrupts
 HRE:          0          0          0          0          0          0          0          0   Hyper-V reenlightenment interrupts
 HVS:          0          0          0          0          0          0          0          0   Hyper-V stimer0 interrupts
 ERR:          0
 MIS:          0
 PIN:          0          0          0          0          0          0          0          0   Posted-interrupt notification event
 NPI:          0          0          0          0          0          0          0          0   Nested posted-interrupt event
 PIW:          0          0          0          0          0          0          0          0   Posted-interrupt wakeup event

Syniurge commented 6 years ago

Thanks, the contents of /sys/kernel/debug/i2c_amd_mp2/*/info would also be interesting (/sys/kernel/debug is only accessible by root).

splav commented 6 years ago

@Syniurge here is the gist

P.S. switching virtual terminal to some else and back also helps.

Syniurge commented 6 years ago

Oh, the big difference with the Yoga 530 is that your touchpad's HID reports are shorter and go through the C2P registers, while mine are 63 bytes long and go through DMA, which is slower.

A quick add: the touchpad freezes more often at two-finger scroll attempts. Right now it freezes at each two-finger touches.

This is a good clue too, I didn't realize this was possible but what might happen is Linux's do_irq() sending an EOI (End of Interrupt) to the IO-APIC too late, after the C2P registers get unlocked by the worker if this one is running on another CPU core.

I'll push a possible fix in a branch in a few minutes.

Syniurge commented 6 years ago

https://github.com/Syniurge/i2c-amd-mp2/tree/wip-v11-and-ideapad-fix

Does this get rid of the timeouts?

splav commented 6 years ago

@Syniurge actually it got worse. Something went wrong with IRQ and the module crashed. But no timeouts =) Here is the dmesg.

JanValiska commented 6 years ago

I'm trying wip-v11-and-ideapad-fix version for about 30 minutes now and it works fine. If i encounter some timeout i'll tell you.

For completnes there is content of my /sys/kernel/debug/i2c_amd_mp2/0000:03:00.7/info:

MP2 Device Information:
========================
        MP2 C2P Message Register Dump:

AMD_C2P_MSG0 -                  0x90020a01
AMD_C2P_MSG1 -                  0x10402c10
AMD_C2P_MSG2 -                  0xff287e00
AMD_C2P_MSG3 -                  0x0000
AMD_C2P_MSG4 -                  0x400306
AMD_C2P_MSG5 -                  0x170025
AMD_C2P_MSG6 -                  0x230022
AMD_C2P_MSG7 -                  0x7f2706cb
AMD_C2P_MSG8 -                  0x0007
AMD_C2P_MSG9 -                  0x0000

        MP2 P2C Message Register Dump:

AMD_P2C_MSG1 -                  0x0000
AMD_P2C_MSG2 -                  0x0000
AMD_P2C_MSG3 -                  0x40400000
AMD_P2C_MSG_INTEN -             0x0000
AMD_P2C_MSG_INTSTS -            0x000f

splav commented 6 years ago

Strange.. I'll try once more later.

Syniurge commented 6 years ago

[    3.053941] i2c_amd_mp2 AMDI0011:01: i2c write timed out
[    3.053957] i2c_hid i2c-MSFT0001:00: failed to change power setting.

9e4ce1a1f482114ff32307711847cd544358a95f may help with this early timeout that might be random.

IIRC this write is the second command sent by i2c-hid to the MP2. The first command is "bus enable" and wasn't locking so again there was a chance that the second command got sent before the first EOI and the next interrupt got lost.

JanValiska commented 6 years ago

Using https://github.com/Syniurge/i2c-amd-mp2/commit/9e4ce1a1f482114ff32307711847cd544358a95f.

After ~5 minutes after resume from suspend i got timeout. But this time touchpad stopped working completely. Reloading i2c_amd_mp2 module helped.

Dmesg:

[Sun Nov 18 15:47:34 2018] irq 47: nobody cared (try booting with the "irqpoll" option)                                                                                                       
[Sun Nov 18 15:47:34 2018] CPU: 0 PID: 22356 Comm: irq/69-MSFT0001 Tainted: G        W  OE     4.19.2-arch1-1-ARCH #1                                                                         
[Sun Nov 18 15:47:34 2018] Hardware name: LENOVO 81H1/LNVNB161216, BIOS 8PCN45WW 08/17/2018                                                                                                   
[Sun Nov 18 15:47:34 2018] Call Trace:                                                                                                                                                        
[Sun Nov 18 15:47:34 2018]  <IRQ>                                                                                                                                                             
[Sun Nov 18 15:47:34 2018]  dump_stack+0x5c/0x80                                                                                                                                              
[Sun Nov 18 15:47:34 2018]  __report_bad_irq+0x37/0xb1                                                                                                                                        
[Sun Nov 18 15:47:34 2018]  note_interrupt.cold.0+0xa/0x6f                                                                                                                                    
[Sun Nov 18 15:47:34 2018]  handle_irq_event_percpu+0x6a/0x80                                                                                                                                 
[Sun Nov 18 15:47:34 2018]  handle_irq_event+0x37/0x54                                                                                                                                        
[Sun Nov 18 15:47:34 2018]  handle_fasteoi_irq+0xae/0x160                                                                                                                                     
[Sun Nov 18 15:47:34 2018]  handle_irq+0x1f/0x30                                                                                                                                              
[Sun Nov 18 15:47:34 2018]  do_IRQ+0x61/0x100                                                                                                                                                 
[Sun Nov 18 15:47:34 2018]  common_interrupt+0xf/0xf                                                                                                                                          
[Sun Nov 18 15:47:34 2018]  </IRQ>                                                                                                                                                            
[Sun Nov 18 15:47:34 2018] RIP: 0010:mutex_spin_on_owner+0x8a/0xa0                                                                                                                            
[Sun Nov 18 15:47:34 2018] Code: 48 85 c0 75 0b 4d 85 ed 74 16 4c 3b 6d 10 74 18 31 db e8 29 44 02 00 89 d8 5b 5d 41 5c 41 5d c3 48 8b 45 00 a8 01 75 e8 f3 90 <48> 8b 45 00 48 83 e0 f8 48 39
 c3 74 92 bb 01 00 00 00 eb d4 66 90                                                                                                                                                          
[Sun Nov 18 15:47:34 2018] RSP: 0018:ffffaa19cca6fcd8 EFLAGS: 00000246 ORIG_RAX: ffffffffffffffc9                                                                                             
[Sun Nov 18 15:47:34 2018] RAX: 0000000000000000 RBX: ffff95091be51e40 RCX: 0000000000000000                                                                                                  
[Sun Nov 18 15:47:34 2018] RDX: 0000000000000001 RSI: ffff95091be51e40 RDI: 0000000000000000                                                                                                  
[Sun Nov 18 15:47:34 2018] RBP: ffff95092b1e8bb8 R08: ffffffffc04a0260 R09: 0000000000000fff                                                                                                  
[Sun Nov 18 15:47:34 2018] R10: 0000000000000000 R11: ffff95091be52248 R12: 0000000000000000                                                                                                  
[Sun Nov 18 15:47:34 2018] R13: 0000000000000000 R14: ffff95092b1e8b98 R15: ffff95092b1e8bb8                                                                                                  
[Sun Nov 18 15:47:34 2018]  ? mutex_spin_on_owner+0x1c/0xa0                                                                                                                                   
[Sun Nov 18 15:47:34 2018]  __mutex_lock.isra.1+0x433/0x530
[Sun Nov 18 15:47:34 2018]  amd_mp2_read+0x5a/0x140 [i2c_amd_mp2]
[Sun Nov 18 15:47:34 2018]  i2c_amd_xfer+0x3a/0xc0 [i2c_amd_mp2]
[Sun Nov 18 15:47:34 2018]  __i2c_transfer+0x142/0x480
[Sun Nov 18 15:47:34 2018]  ? irq_forced_thread_fn+0x70/0x70
[Sun Nov 18 15:47:34 2018]  i2c_transfer+0x51/0xc0
[Sun Nov 18 15:47:34 2018]  i2c_transfer_buffer_flags+0x4c/0x70
[Sun Nov 18 15:47:34 2018]  i2c_hid_irq+0x3c/0x130 [i2c_hid]
[Sun Nov 18 15:47:34 2018]  irq_thread_fn+0x1f/0x60
[Sun Nov 18 15:47:34 2018]  irq_thread+0xe7/0x160
[Sun Nov 18 15:47:34 2018]  ? wake_threads_waitq+0x30/0x30
[Sun Nov 18 15:47:34 2018]  ? irq_thread_dtor+0x80/0x80
[Sun Nov 18 15:47:34 2018]  kthread+0x112/0x130
[Sun Nov 18 15:47:34 2018]  ? kthread_park+0x80/0x80
[Sun Nov 18 15:47:34 2018]  ret_from_fork+0x22/0x40
[Sun Nov 18 15:47:34 2018] handlers:
[Sun Nov 18 15:47:34 2018] [<00000000e134ab25>] amd_mp2_irq_isr [i2c_amd_mp2]
[Sun Nov 18 15:47:34 2018] Disabling IRQ #47
[Sun Nov 18 15:47:40 2018] i2c_amd_mp2 AMDI0011:01: i2c read timed out

Syniurge commented 6 years ago

Probably another synchronization issue that should be fixed by 7d995cc12144ce71c8a14bf8654d3390bef619a8.

Maybe the last one though, let me know if you still encounter timeouts.

splav commented 6 years ago

Unfortunately I've just had a crash with latest changes. dmesg

Syniurge commented 6 years ago

Hoping that be7b0a474d6ec514c4d05e51cbb39de6db7aa430 will help and put an end to this issue.

Btw @splav are the timeouts gone or did you not use the touchpad for the first 5 mins?

Also if I'm not mistaken "irq NN: nobody cared" isn't a critical error, reloading the module should be enough to bring the touchpad back.

splav commented 6 years ago

Yes, the timeouts are gone. Just updated the module - so far so good.

splav commented 6 years ago

After 5 hours of active work no freezes and read timeouts. The laptop is totally usable, finally. Thank you very much!

The only strange lines in dmesg are:

[   10.576646] i2c_amd_mp2 AMDI0011:01: i2c write timed out
[   10.576672] i2c_hid i2c-MSFT0001:00: failed to change power setting.

But it seems to have no negative impact.

splav commented 6 years ago

Just got same irq error after booting a cold notebook from outdoor. dmesg and info

splav commented 5 years ago

Still getting same IRQ errors occasionally. Should this be tracked by another issue or this one?

JanValiska commented 5 years ago

I can confirm that with latest version i didn't get timeout anymore. But i also got irq error 5 minutes after boot. It was two days ago. Since then there was'n any IRQ error. It is strange.

Syniurge commented 5 years ago

The irq nobdy cared error isn't the original issue but anyway this repository is mainly here to provide a DKMS version of an upcoming (hopefully) mainline driver, it's not like I need it to be perfectly organized.

I'm working on a v11 version of the patch with one more irq handling fix that may help. A bit thin on free time right now but I'll probably push it within the next two days.

Syniurge commented 5 years ago

a9e6d5af37ba84a8882c5d5a42af5b830b8433f6 contains one more change that should ensure that interrupts always get serviced and avoiding the irq: nobody cared error.

Please tell me if there are still issues (also regarding the runtime PM support, which autosuspends the MP2 after 1s of idling, like most I2C controller drivers do since resuming is very cheap), I'll submit the patch upstream tomorrow.

Syniurge / i2c-amd-mp2

i2c read timed out #1