2022-04-18 12:38:02

by Toke Høiland-Jørgensen

[permalink] [raw]
Subject: iwlwifi devices disappear after suspend on kernel 5.17

Hi Luca

I've started seeing issues with my iwlwifi interface going away after
suspend. I get errors like these in dmesg:

[104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice (Reason: 3=DEAUTH_LEAVING)
[104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK from CSME on HOST_GOES_DOWN message
[104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the SAP_ME_MSG_CHECK_SHARED_AREA message -19

And when the host comes back up, there is no connectivity. Restarting
iwd fixes the problem.

This is on a 5.17.3 kernel (Arch Linux distribution kernel), and lspci
says the WiFi device is an "Intel Corporation Wi-Fi 6 AX201".

Any ideas? :)

-Toke


2022-04-19 08:19:49

by Grumbach, Emmanuel

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

Hi Toke,

On Tue, 2022-04-19 at 06:24 +0000, Coelho, Luciano wrote:
> On Mon, 2022-04-18 at 13:36 +0200, Toke Høiland-Jørgensen wrote:
> > Hi Luca
>
> Hi Toke,
>
> > I've started seeing issues with my iwlwifi interface going away after
> > suspend. I get errors like these in dmesg:
> >
> > [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice (Reason:
> > 3=DEAUTH_LEAVING)
> > [104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK from
> > CSME on HOST_GOES_DOWN message
> > [104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the
> > SAP_ME_MSG_CHECK_SHARED_AREA message -19
> >
> > And when the host comes back up, there is no connectivity. Restarting
> > iwd fixes the problem.
> >
> > This is on a 5.17.3 kernel (Arch Linux distribution kernel), and lspci
> > says the WiFi device is an "Intel Corporation Wi-Fi 6 AX201".
> >
> > Any ideas? :)
>
> This seems to be related to iwlmei, so I added Emmanuel to the thread.
>

Can we have the full dmesg output?
What NIC / platform do you have?
Do you have AMT configured in the BIOS?
Did you enable wireless operation in AMT?

2022-04-19 09:13:31

by Luciano Coelho

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

On Mon, 2022-04-18 at 13:36 +0200, Toke Høiland-Jørgensen wrote:
> Hi Luca

Hi Toke,

> I've started seeing issues with my iwlwifi interface going away after
> suspend. I get errors like these in dmesg:
>
> [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice (Reason: 3=DEAUTH_LEAVING)
> [104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK from CSME on HOST_GOES_DOWN message
> [104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the SAP_ME_MSG_CHECK_SHARED_AREA message -19
>
> And when the host comes back up, there is no connectivity. Restarting
> iwd fixes the problem.
>
> This is on a 5.17.3 kernel (Arch Linux distribution kernel), and lspci
> says the WiFi device is an "Intel Corporation Wi-Fi 6 AX201".
>
> Any ideas? :)

This seems to be related to iwlmei, so I added Emmanuel to the thread.

Additionally, I'm not working with Intel's WiFi any more, so I also
CCed Gregory, who is the new iwlwifi maintainer.

--
Cheers,
Luca.

2022-04-19 18:33:09

by Grumbach, Emmanuel

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

On Tue, 2022-04-19 at 09:41 +0300, Grumbach, Emmanuel wrote:
> Hi Toke,
>
> On Tue, 2022-04-19 at 06:24 +0000, Coelho, Luciano wrote:
> > On Mon, 2022-04-18 at 13:36 +0200, Toke Høiland-Jørgensen wrote:
> > > Hi Luca
> >
> > Hi Toke,
> >
> > > I've started seeing issues with my iwlwifi interface going away after
> > > suspend. I get errors like these in dmesg:
> > >
> > > [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice (Reason:
> > > 3=DEAUTH_LEAVING)
> > > [104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK
> > > from
> > > CSME on HOST_GOES_DOWN message
> > > [104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the
> > > SAP_ME_MSG_CHECK_SHARED_AREA message -19
> > >
> > > And when the host comes back up, there is no connectivity. Restarting
> > > iwd fixes the problem.
> > >
> > > This is on a 5.17.3 kernel (Arch Linux distribution kernel), and lspci
> > > says the WiFi device is an "Intel Corporation Wi-Fi 6 AX201".
> > >
> > > Any ideas? :)
> >
> > This seems to be related to iwlmei, so I added Emmanuel to the thread.
> >
>
> Can we have the full dmesg output?
> What NIC / platform do you have?
> Do you have AMT configured in the BIOS?
> Did you enable wireless operation in AMT?

Ah - this is AX201, ok, but I still need the platform and thefull dmesg :-)

2022-04-19 19:12:22

by Grumbach, Emmanuel

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

On Tue, 2022-04-19 at 20:46 +0300, Grumbach, Emmanuel wrote:
> On Tue, 2022-04-19 at 13:33 +0200, Toke Høiland-Jørgensen wrote:
> > "Grumbach, Emmanuel" <[email protected]> writes:
> >
> > > On Tue, 2022-04-19 at 09:41 +0300, Grumbach, Emmanuel wrote:
> > > > Hi Toke,
> > > >
> > > > On Tue, 2022-04-19 at 06:24 +0000, Coelho, Luciano wrote:
> > > > > On Mon, 2022-04-18 at 13:36 +0200, Toke Høiland-Jørgensen wrote:
> > > > > > Hi Luca
> > > > >
> > > > > Hi Toke,
> > > > >
> > > > > > I've started seeing issues with my iwlwifi interface going away after
> > > > > > suspend. I get errors like these in dmesg:
> > > > > >
> > > > > > [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice (Reason:
> > > > > > 3=DEAUTH_LEAVING)
> > > > > > [104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get
> > > > > > ACK
> > > > > > from
> > > > > > CSME on HOST_GOES_DOWN message
> > > > > > [104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send
> > > > > > the
> > > > > > SAP_ME_MSG_CHECK_SHARED_AREA message -19
> > > > > >
> > > > > > And when the host comes back up, there is no connectivity. Restarting
> > > > > > iwd fixes the problem.
> > > > > >
> > > > > > This is on a 5.17.3 kernel (Arch Linux distribution kernel), and lspci
> > > > > > says the WiFi device is an "Intel Corporation Wi-Fi 6 AX201".
> > > > > >
> > > > > > Any ideas? :)
> > > > >
> > > > > This seems to be related to iwlmei, so I added Emmanuel to the thread.
> > > > >
> > > >
> > > > Can we have the full dmesg output?
> > > > What NIC / platform do you have?
> > > > Do you have AMT configured in the BIOS?
> > > > Did you enable wireless operation in AMT?
> > >
> > > Ah - this is AX201, ok, but I still need the platform and thefull dmesg :-)
> >
> > It's a Lenovo ThinkPad X1 Carbon Gen 9, model 20XXS3HC26; AMT is set to
> > "Disabled" in the BIOS. I rebooted and did a suspend/wake cycle after
> > connecting to the WiFi network, which produced the dmesg below.
>
> Thanks.
> This is a Skylake platform and we didn't have a handshake with CSME which is not enabled. We are
> missing a check before we shut down iwlmei.

Hm... no, that seems to be 11th generation... so TigerLake?
But still, the fix below seems relevant.

> Can you check this?
>
> diff --git a/drivers/net/wireless/intel/iwlwifi/mei/main.c
> b/drivers/net/wireless/intel/iwlwifi/mei/main.c
> index 8d61044a6ed3..7b45382c813c 100644
> --- a/drivers/net/wireless/intel/iwlwifi/mei/main.c
> +++ b/drivers/net/wireless/intel/iwlwifi/mei/main.c
> @@ -1923,29 +1923,33 @@ static void iwl_mei_remove(struct mei_cl_device *cldev)
>  
>         mutex_lock(&iwl_mei_mutex);
>  
> -       /*
> -        * Tell CSME that we are going down so that it won't access the
> -        * memory anymore, make sure this message goes through immediately.
> -        */
> -       mei->csa_throttled = false;
> -       iwl_mei_send_sap_msg(mei->cldev,
> -                            SAP_MSG_NOTIF_HOST_GOES_DOWN);
> +       if (iwl_mei_is_connected())
> +       {
> +               /*
> +                * Tell CSME that we are going down so that it won't access the
> +                * memory anymore, make sure this message goes through immediately.
> +                */
> +               mei->csa_throttled = false;
> +               iwl_mei_send_sap_msg(mei->cldev,
> +                                    SAP_MSG_NOTIF_HOST_GOES_DOWN);
>  
> -       for (i = 0; i < SEND_SAP_MAX_WAIT_ITERATION; i++) {
> -               if (!iwl_mei_host_to_me_data_pending(mei))
> -                       break;
> +               for (i = 0; i < SEND_SAP_MAX_WAIT_ITERATION; i++) {
> +                       if (!iwl_mei_host_to_me_data_pending(mei))
> +                               break;
>  
> -               msleep(5);
> -       }
> +                       msleep(5);
> +               }
>  
> -       /*
> -        * If we couldn't make sure that CSME saw the HOST_GOES_DOWN message,
> -        * it means that it will probably keep reading memory that we are going
> -        * to unmap and free, expect IOMMU error messages.
> -        */
> -       if (i == SEND_SAP_MAX_WAIT_ITERATION)
> -               dev_err(&mei->cldev->dev,
> -                       "Couldn't get ACK from CSME on HOST_GOES_DOWN message\n");
> +               /*
> +                * If we couldn't make sure that CSME saw the HOST_GOES_DOWN
> +                * message, it means that it will probably keep reading memory
> +                * that we are going to unmap and free, expect IOMMU error
> +                * messages.
> +                */
> +               if (i == SEND_SAP_MAX_WAIT_ITERATION)
> +                       dev_err(&mei->cldev->dev,
> +                               "Couldn't get ACK from CSME on HOST_GOES_DOWN message\n");
> +       }
>  
>         mutex_unlock(&iwl_mei_mutex);
>  
>
> Although I think it'll just fix the error print and nothing more than this
>

2022-04-19 19:27:42

by Luciano Coelho

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

On Tue, 2022-04-19 at 13:19 +0200, Toke Høiland-Jørgensen wrote:
> "Coelho, Luciano" <[email protected]> writes:
>
> > On Mon, 2022-04-18 at 13:36 +0200, Toke Høiland-Jørgensen wrote:
> > > Hi Luca
> >
> > Hi Toke,
> >
> > > I've started seeing issues with my iwlwifi interface going away after
> > > suspend. I get errors like these in dmesg:
> > >
> > > [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice (Reason: 3=DEAUTH_LEAVING)
> > > [104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK from CSME on HOST_GOES_DOWN message
> > > [104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the SAP_ME_MSG_CHECK_SHARED_AREA message -19
> > >
> > > And when the host comes back up, there is no connectivity. Restarting
> > > iwd fixes the problem.
> > >
> > > This is on a 5.17.3 kernel (Arch Linux distribution kernel), and lspci
> > > says the WiFi device is an "Intel Corporation Wi-Fi 6 AX201".
> > >
> > > Any ideas? :)
> >
> > This seems to be related to iwlmei, so I added Emmanuel to the thread.
> >
> > Additionally, I'm not working with Intel's WiFi any more, so I also
> > CCed Gregory, who is the new iwlwifi maintainer.
>
> Thanks for the forward! How about updating MAINTAINERS to reflect this? :)

It's already on its way:

https://patchwork.kernel.org/project/linux-wireless/patch/[email protected]/

--
Cheers,
Luca.

2022-04-20 05:57:18

by Toke Høiland-Jørgensen

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

"Grumbach, Emmanuel" <[email protected]> writes:

> On Tue, 2022-04-19 at 20:46 +0300, Grumbach, Emmanuel wrote:
>> On Tue, 2022-04-19 at 13:33 +0200, Toke Høiland-Jørgensen wrote:
>> > "Grumbach, Emmanuel" <[email protected]> writes:
>> >
>> > > On Tue, 2022-04-19 at 09:41 +0300, Grumbach, Emmanuel wrote:
>> > > > Hi Toke,
>> > > >
>> > > > On Tue, 2022-04-19 at 06:24 +0000, Coelho, Luciano wrote:
>> > > > > On Mon, 2022-04-18 at 13:36 +0200, Toke Høiland-Jørgensen wrote:
>> > > > > > Hi Luca
>> > > > >
>> > > > > Hi Toke,
>> > > > >
>> > > > > > I've started seeing issues with my iwlwifi interface going away after
>> > > > > > suspend. I get errors like these in dmesg:
>> > > > > >
>> > > > > > [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice (Reason:
>> > > > > > 3=DEAUTH_LEAVING)
>> > > > > > [104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get
>> > > > > > ACK
>> > > > > > from
>> > > > > > CSME on HOST_GOES_DOWN message
>> > > > > > [104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send
>> > > > > > the
>> > > > > > SAP_ME_MSG_CHECK_SHARED_AREA message -19
>> > > > > >
>> > > > > > And when the host comes back up, there is no connectivity. Restarting
>> > > > > > iwd fixes the problem.
>> > > > > >
>> > > > > > This is on a 5.17.3 kernel (Arch Linux distribution kernel), and lspci
>> > > > > > says the WiFi device is an "Intel Corporation Wi-Fi 6 AX201".
>> > > > > >
>> > > > > > Any ideas? :)
>> > > > >
>> > > > > This seems to be related to iwlmei, so I added Emmanuel to the thread.
>> > > > >
>> > > >
>> > > > Can we have the full dmesg output?
>> > > > What NIC / platform do you have?
>> > > > Do you have AMT configured in the BIOS?
>> > > > Did you enable wireless operation in AMT?
>> > >
>> > > Ah - this is AX201, ok, but I still need the platform and thefull dmesg :-)
>> >
>> > It's a Lenovo ThinkPad X1 Carbon Gen 9, model 20XXS3HC26; AMT is set to
>> > "Disabled" in the BIOS. I rebooted and did a suspend/wake cycle after
>> > connecting to the WiFi network, which produced the dmesg below.
>>
>> Thanks.
>> This is a Skylake platform and we didn't have a handshake with CSME which is not enabled. We are
>> missing a check before we shut down iwlmei.
>
> Hm... no, that seems to be 11th generation... so TigerLake?
> But still, the fix below seems relevant.

Hmm, tried the patch and the message is still there (as is the bug)...
The error code is different now, though:

[ 65.773731] wlan0: deauthenticating from 4c:60:de:ea:b8:5a by local choice (Reason: 3=DEAUTH_LEAVING)
[ 65.973767] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK from CSME on HOST_GOES_DOWN message
[ 65.973845] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the SAP_ME_MSG_CHECK_SHARED_AREA message -14

-Toke

2022-04-20 10:35:50

by Toke Høiland-Jørgensen

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

"Grumbach, Emmanuel" <[email protected]> writes:

> On Tue, 2022-04-19 at 22:58 +0200, Toke Høiland-Jørgensen wrote:
>> "Grumbach, Emmanuel" <[email protected]> writes:
>>
>> > On Tue, 2022-04-19 at 20:46 +0300, Grumbach, Emmanuel wrote:
>> > > On Tue, 2022-04-19 at 13:33 +0200, Toke Høiland-Jørgensen wrote:
>> > > > "Grumbach, Emmanuel" <[email protected]> writes:
>> > > >
>> > > > > On Tue, 2022-04-19 at 09:41 +0300, Grumbach, Emmanuel wrote:
>> > > > > > Hi Toke,
>> > > > > >
>> > > > > > On Tue, 2022-04-19 at 06:24 +0000, Coelho, Luciano wrote:
>> > > > > > > On Mon, 2022-04-18 at 13:36 +0200, Toke Høiland-Jørgensen wrote:
>> > > > > > > > Hi Luca
>> > > > > > >
>> > > > > > > Hi Toke,
>> > > > > > >
>> > > > > > > > I've started seeing issues with my iwlwifi interface going away after
>> > > > > > > > suspend. I get errors like these in dmesg:
>> > > > > > > >
>> > > > > > > > [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice
>> > > > > > > > (Reason:
>> > > > > > > > 3=DEAUTH_LEAVING)
>> > > > > > > > [104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't
>> > > > > > > > get
>> > > > > > > > ACK
>> > > > > > > > from
>> > > > > > > > CSME on HOST_GOES_DOWN message
>> > > > > > > > [104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to
>> > > > > > > > send
>> > > > > > > > the
>> > > > > > > > SAP_ME_MSG_CHECK_SHARED_AREA message -19
>> > > > > > > >
>> > > > > > > > And when the host comes back up, there is no connectivity. Restarting
>> > > > > > > > iwd fixes the problem.
>> > > > > > > >
>> > > > > > > > This is on a 5.17.3 kernel (Arch Linux distribution kernel), and lspci
>> > > > > > > > says the WiFi device is an "Intel Corporation Wi-Fi 6 AX201".
>> > > > > > > >
>> > > > > > > > Any ideas? :)
>> > > > > > >
>> > > > > > > This seems to be related to iwlmei, so I added Emmanuel to the thread.
>> > > > > > >
>> > > > > >
>> > > > > > Can we have the full dmesg output?
>> > > > > > What NIC / platform do you have?
>> > > > > > Do you have AMT configured in the BIOS?
>> > > > > > Did you enable wireless operation in AMT?
>> > > > >
>> > > > > Ah - this is AX201, ok, but I still need the platform and thefull dmesg :-)
>> > > >
>> > > > It's a Lenovo ThinkPad X1 Carbon Gen 9, model 20XXS3HC26; AMT is set to
>> > > > "Disabled" in the BIOS. I rebooted and did a suspend/wake cycle after
>> > > > connecting to the WiFi network, which produced the dmesg below.
>> > >
>> > > Thanks.
>> > > This is a Skylake platform and we didn't have a handshake with CSME which is not enabled. We
>> > > are
>> > > missing a check before we shut down iwlmei.
>> >
>> > Hm... no, that seems to be 11th generation... so TigerLake?
>> > But still, the fix below seems relevant.
>>
>> Hmm, tried the patch and the message is still there (as is the bug)...
>> The error code is different now, though:
>>
>> [   65.773731] wlan0: deauthenticating from 4c:60:de:ea:b8:5a by local choice (Reason:
>> 3=DEAUTH_LEAVING)
>> [   65.973767] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK from
>> CSME on HOST_GOES_DOWN message
>> [   65.973845] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the
>> SAP_ME_MSG_CHECK_SHARED_AREA message -14
>>
>
> So you do get a SAP connection. Weird.
> Checking with the CSME guys.
> Can you get dynamic debug prints for iwlmei module?
> Best would be tracing with -e iwlmei_sap_cmd

Sure! Recorded these while doing a suspend-restore, then restarting iwd:

kworker/u16:34-34555 [004] 46452.506238: iwlmei_sap_cmd: sap_cmd Tx: type 1010 len 8 seq 11
kworker/u16:34-34555 [004] 46452.506243: iwlmei_me_msg: ME message: Tx: type 3 seq 8
kworker/u16:34-34555 [004] 46452.559407: iwlmei_sap_cmd: sap_cmd Tx: type 1001 len 4 seq 12
kworker/u16:14-33311 [004] 46452.573077: iwlmei_sap_cmd: sap_cmd Tx: type 1006 len 0 seq 13
kworker/u16:14-33311 [004] 46452.573078: iwlmei_me_msg: ME message: Tx: type 3 seq 10
kworker/4:0-34305 [004] 46452.706737: iwlmei_me_msg: ME message: Tx: type 3 seq 11
kworker/u16:8-34462 [000] 46457.939453: iwlmei_me_msg: ME message: Tx: type 1 seq 1
kworker/4:2-32196 [004] 46457.940910: iwlmei_me_msg: ME message: Rx: type 2 seq 81
kworker/4:2-32196 [004] 46457.940912: iwlmei_sap_cmd: sap_cmd Tx: type 1013 len 0 seq 1
kworker/4:2-32196 [004] 46457.940913: iwlmei_me_msg: ME message: Tx: type 3 seq 2
kworker/4:2-32196 [004] 46457.942056: iwlmei_me_msg: ME message: Rx: type 3 seq 82
kworker/4:2-32196 [004] 46457.942060: iwlmei_sap_cmd: sap_cmd Rx: type 502 len 4 seq 1
kworker/4:2-32196 [004] 46458.042205: iwlmei_me_msg: ME message: Rx: type 3 seq 83
kworker/4:2-32196 [004] 46458.042208: iwlmei_sap_cmd: sap_cmd Rx: type 500 len 1896 seq 2
kworker/4:2-32196 [004] 46458.042209: iwlmei_sap_cmd: sap_cmd Rx: type 512 len 64 seq 3
kworker/4:2-32196 [004] 46458.042214: iwlmei_sap_cmd: sap_cmd Rx: type 502 len 4 seq 4
iwd-35396 [003] 46467.085642: iwlmei_sap_cmd: sap_cmd Tx: type 1008 len 8 seq 2
iwd-35396 [003] 46467.085645: iwlmei_me_msg: ME message: Tx: type 3 seq 3
iwd-35396 [003] 46467.102818: iwlmei_sap_cmd: sap_cmd Tx: type 1008 len 8 seq 3
iwd-35396 [003] 46467.103369: iwlmei_sap_cmd: sap_cmd Tx: type 1016 len 20 seq 4
iwd-35396 [003] 46467.103842: iwlmei_sap_cmd: sap_cmd Tx: type 1001 len 4 seq 5
iwd-35396 [003] 46467.104689: iwlmei_sap_cmd: sap_cmd Tx: type 1002 len 12 seq 6

The dmesg log looked like this (back to -19 in the error):

[46452.483343] wlan0: deauthenticating from 4c:60:de:ea:b8:5a by local choice (Reason: 3=DEAUTH_LEAVING)
[46452.689465] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK from CSME on HOST_GOES_DOWN message
[46452.689567] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the SAP_ME_MSG_CHECK_SHARED_AREA message -19

> I might also need dynamic debgug prints from the mei bus driver but that will be for later.
> Thanks for reporting and providing the data!

You're welcome! Thanks for looking into it :)

-Toke

2022-04-20 13:07:08

by Grumbach, Emmanuel

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

removing Luca

On Wed, 2022-04-20 at 11:49 +0200, Toke Høiland-Jørgensen wrote:


<snip>

> > >
> >
> > So you do get a SAP connection. Weird.
> > Checking with the CSME guys.
> > Can you get dynamic debug prints for iwlmei module?
> > Best would be tracing with -e iwlmei_sap_cmd
>
> Sure! Recorded these while doing a suspend-restore, then restarting iwd:
>
>   kworker/u16:34-34555 [004] 46452.506238: iwlmei_sap_cmd:       sap_cmd Tx: type 1010 len 8 seq
> 11
>   kworker/u16:34-34555 [004] 46452.506243: iwlmei_me_msg:        ME message: Tx: type 3 seq 8
>   kworker/u16:34-34555 [004] 46452.559407: iwlmei_sap_cmd:       sap_cmd Tx: type 1001 len 4 seq
> 12
>   kworker/u16:14-33311 [004] 46452.573077: iwlmei_sap_cmd:       sap_cmd Tx: type 1006 len 0 seq
> 13
>   kworker/u16:14-33311 [004] 46452.573078: iwlmei_me_msg:        ME message: Tx: type 3 seq 10
>      kworker/4:0-34305 [004] 46452.706737: iwlmei_me_msg:        ME message: Tx: type 3 seq 11
>    kworker/u16:8-34462 [000] 46457.939453: iwlmei_me_msg:        ME message: Tx: type 1 seq 1
>      kworker/4:2-32196 [004] 46457.940910: iwlmei_me_msg:        ME message: Rx: type 2 seq 81
>      kworker/4:2-32196 [004] 46457.940912: iwlmei_sap_cmd:       sap_cmd Tx: type 1013 len 0 seq 1
>      kworker/4:2-32196 [004] 46457.940913: iwlmei_me_msg:        ME message: Tx: type 3 seq 2
>      kworker/4:2-32196 [004] 46457.942056: iwlmei_me_msg:        ME message: Rx: type 3 seq 82
>      kworker/4:2-32196 [004] 46457.942060: iwlmei_sap_cmd:       sap_cmd Rx: type 502 len 4 seq 1
>      kworker/4:2-32196 [004] 46458.042205: iwlmei_me_msg:        ME message: Rx: type 3 seq 83
>      kworker/4:2-32196 [004] 46458.042208: iwlmei_sap_cmd:       sap_cmd Rx: type 500 len 1896 seq
> 2
>      kworker/4:2-32196 [004] 46458.042209: iwlmei_sap_cmd:       sap_cmd Rx: type 512 len 64 seq 3
>      kworker/4:2-32196 [004] 46458.042214: iwlmei_sap_cmd:       sap_cmd Rx: type 502 len 4 seq 4
>              iwd-35396 [003] 46467.085642: iwlmei_sap_cmd:       sap_cmd Tx: type 1008 len 8 seq 2
>              iwd-35396 [003] 46467.085645: iwlmei_me_msg:        ME message: Tx: type 3 seq 3
>              iwd-35396 [003] 46467.102818: iwlmei_sap_cmd:       sap_cmd Tx: type 1008 len 8 seq 3
>              iwd-35396 [003] 46467.103369: iwlmei_sap_cmd:       sap_cmd Tx: type 1016 len 20 seq
> 4
>              iwd-35396 [003] 46467.103842: iwlmei_sap_cmd:       sap_cmd Tx: type 1001 len 4 seq 5
>              iwd-35396 [003] 46467.104689: iwlmei_sap_cmd:       sap_cmd Tx: type 1002 len 12 seq
> 6

can you please send the content of tracing unparsed?
There is more binary data that I can use.
I definitely need to talk to the CSME guys, I can see that CSME is alive even if you have AMT
disabled.
Problem: it's holiday season here.

Thanks.


>
> The dmesg log looked like this (back to -19 in the error):
>
> [46452.483343] wlan0: deauthenticating from 4c:60:de:ea:b8:5a by local choice (Reason:
> 3=DEAUTH_LEAVING)
> [46452.689465] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK from
> CSME on HOST_GOES_DOWN message
> [46452.689567] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the
> SAP_ME_MSG_CHECK_SHARED_AREA message -19
>
> > I might also need dynamic debgug prints from the mei bus driver but that will be for later.
> > Thanks for reporting and providing the data!
>
> You're welcome! Thanks for looking into it :)
>
> -Toke

2022-04-20 16:10:30

by Toke Høiland-Jørgensen

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

"Coelho, Luciano" <[email protected]> writes:

> On Mon, 2022-04-18 at 13:36 +0200, Toke Høiland-Jørgensen wrote:
>> Hi Luca
>
> Hi Toke,
>
>> I've started seeing issues with my iwlwifi interface going away after
>> suspend. I get errors like these in dmesg:
>>
>> [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice (Reason: 3=DEAUTH_LEAVING)
>> [104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK from CSME on HOST_GOES_DOWN message
>> [104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the SAP_ME_MSG_CHECK_SHARED_AREA message -19
>>
>> And when the host comes back up, there is no connectivity. Restarting
>> iwd fixes the problem.
>>
>> This is on a 5.17.3 kernel (Arch Linux distribution kernel), and lspci
>> says the WiFi device is an "Intel Corporation Wi-Fi 6 AX201".
>>
>> Any ideas? :)
>
> This seems to be related to iwlmei, so I added Emmanuel to the thread.
>
> Additionally, I'm not working with Intel's WiFi any more, so I also
> CCed Gregory, who is the new iwlwifi maintainer.

Thanks for the forward! How about updating MAINTAINERS to reflect this? :)

-Toke

2022-04-20 18:04:21

by Grumbach, Emmanuel

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

On Tue, 2022-04-19 at 13:33 +0200, Toke Høiland-Jørgensen wrote:
> "Grumbach, Emmanuel" <[email protected]> writes:
>
> > On Tue, 2022-04-19 at 09:41 +0300, Grumbach, Emmanuel wrote:
> > > Hi Toke,
> > >
> > > On Tue, 2022-04-19 at 06:24 +0000, Coelho, Luciano wrote:
> > > > On Mon, 2022-04-18 at 13:36 +0200, Toke Høiland-Jørgensen wrote:
> > > > > Hi Luca
> > > >
> > > > Hi Toke,
> > > >
> > > > > I've started seeing issues with my iwlwifi interface going away after
> > > > > suspend. I get errors like these in dmesg:
> > > > >
> > > > > [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice (Reason:
> > > > > 3=DEAUTH_LEAVING)
> > > > > [104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK
> > > > > from
> > > > > CSME on HOST_GOES_DOWN message
> > > > > [104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send
> > > > > the
> > > > > SAP_ME_MSG_CHECK_SHARED_AREA message -19
> > > > >
> > > > > And when the host comes back up, there is no connectivity. Restarting
> > > > > iwd fixes the problem.
> > > > >
> > > > > This is on a 5.17.3 kernel (Arch Linux distribution kernel), and lspci
> > > > > says the WiFi device is an "Intel Corporation Wi-Fi 6 AX201".
> > > > >
> > > > > Any ideas? :)
> > > >
> > > > This seems to be related to iwlmei, so I added Emmanuel to the thread.
> > > >
> > >
> > > Can we have the full dmesg output?
> > > What NIC / platform do you have?
> > > Do you have AMT configured in the BIOS?
> > > Did you enable wireless operation in AMT?
> >
> > Ah - this is AX201, ok, but I still need the platform and thefull dmesg :-)
>
> It's a Lenovo ThinkPad X1 Carbon Gen 9, model 20XXS3HC26; AMT is set to
> "Disabled" in the BIOS. I rebooted and did a suspend/wake cycle after
> connecting to the WiFi network, which produced the dmesg below.

Thanks.
This is a Skylake platform and we didn't have a handshake with CSME which is not enabled. We are
missing a check before we shut down iwlmei.
Can you check this?

diff --git a/drivers/net/wireless/intel/iwlwifi/mei/main.c
b/drivers/net/wireless/intel/iwlwifi/mei/main.c
index 8d61044a6ed3..7b45382c813c 100644
--- a/drivers/net/wireless/intel/iwlwifi/mei/main.c
+++ b/drivers/net/wireless/intel/iwlwifi/mei/main.c
@@ -1923,29 +1923,33 @@ static void iwl_mei_remove(struct mei_cl_device *cldev)

mutex_lock(&iwl_mei_mutex);

- /*
- * Tell CSME that we are going down so that it won't access the
- * memory anymore, make sure this message goes through immediately.
- */
- mei->csa_throttled = false;
- iwl_mei_send_sap_msg(mei->cldev,
- SAP_MSG_NOTIF_HOST_GOES_DOWN);
+ if (iwl_mei_is_connected())
+ {
+ /*
+ * Tell CSME that we are going down so that it won't access the
+ * memory anymore, make sure this message goes through immediately.
+ */
+ mei->csa_throttled = false;
+ iwl_mei_send_sap_msg(mei->cldev,
+ SAP_MSG_NOTIF_HOST_GOES_DOWN);

- for (i = 0; i < SEND_SAP_MAX_WAIT_ITERATION; i++) {
- if (!iwl_mei_host_to_me_data_pending(mei))
- break;
+ for (i = 0; i < SEND_SAP_MAX_WAIT_ITERATION; i++) {
+ if (!iwl_mei_host_to_me_data_pending(mei))
+ break;

- msleep(5);
- }
+ msleep(5);
+ }

- /*
- * If we couldn't make sure that CSME saw the HOST_GOES_DOWN message,
- * it means that it will probably keep reading memory that we are going
- * to unmap and free, expect IOMMU error messages.
- */
- if (i == SEND_SAP_MAX_WAIT_ITERATION)
- dev_err(&mei->cldev->dev,
- "Couldn't get ACK from CSME on HOST_GOES_DOWN message\n");
+ /*
+ * If we couldn't make sure that CSME saw the HOST_GOES_DOWN
+ * message, it means that it will probably keep reading memory
+ * that we are going to unmap and free, expect IOMMU error
+ * messages.
+ */
+ if (i == SEND_SAP_MAX_WAIT_ITERATION)
+ dev_err(&mei->cldev->dev,
+ "Couldn't get ACK from CSME on HOST_GOES_DOWN message\n");
+ }

mutex_unlock(&iwl_mei_mutex);


Although I think it'll just fix the error print and nothing more than this

2022-04-21 12:30:01

by Toke Høiland-Jørgensen

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

"Grumbach, Emmanuel" <[email protected]> writes:

> On Tue, 2022-04-19 at 09:41 +0300, Grumbach, Emmanuel wrote:
>> Hi Toke,
>>
>> On Tue, 2022-04-19 at 06:24 +0000, Coelho, Luciano wrote:
>> > On Mon, 2022-04-18 at 13:36 +0200, Toke Høiland-Jørgensen wrote:
>> > > Hi Luca
>> >
>> > Hi Toke,
>> >
>> > > I've started seeing issues with my iwlwifi interface going away after
>> > > suspend. I get errors like these in dmesg:
>> > >
>> > > [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice (Reason:
>> > > 3=DEAUTH_LEAVING)
>> > > [104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK
>> > > from
>> > > CSME on HOST_GOES_DOWN message
>> > > [104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the
>> > > SAP_ME_MSG_CHECK_SHARED_AREA message -19
>> > >
>> > > And when the host comes back up, there is no connectivity. Restarting
>> > > iwd fixes the problem.
>> > >
>> > > This is on a 5.17.3 kernel (Arch Linux distribution kernel), and lspci
>> > > says the WiFi device is an "Intel Corporation Wi-Fi 6 AX201".
>> > >
>> > > Any ideas? :)
>> >
>> > This seems to be related to iwlmei, so I added Emmanuel to the thread.
>> >
>>
>> Can we have the full dmesg output?
>> What NIC / platform do you have?
>> Do you have AMT configured in the BIOS?
>> Did you enable wireless operation in AMT?
>
> Ah - this is AX201, ok, but I still need the platform and thefull dmesg :-)

It's a Lenovo ThinkPad X1 Carbon Gen 9, model 20XXS3HC26; AMT is set to
"Disabled" in the BIOS. I rebooted and did a suspend/wake cycle after
connecting to the WiFi network, which produced the dmesg below.

-Toke

[ 0.000000] Linux version 5.17.3-arch1-1 (linux@archlinux) (gcc (GCC) 11.2.0, GNU ld (GNU Binutils) 2.38) #1 SMP PREEMPT Thu, 14 Apr 2022 01:18:36 +0000
[ 0.000000] Command line: root=/dev/mapper/cryptroot rootflags=subvol=/root init=/usr/lib/systemd/systemd cryptdevice=/dev/disk/by-uuid/xxxx-xxxx-xxxx-xxxx-xxxxxx:cryptroot:allow-discards rw quiet
[ 0.000000] x86/split lock detection: #AC: crashing the kernel on kernel split_locks and warning on user-space split_locks
[ 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: Supporting XSAVE feature 0x020: 'AVX-512 opmask'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
[ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
[ 0.000000] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64
[ 0.000000] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512
[ 0.000000] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024
[ 0.000000] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8
[ 0.000000] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.
[ 0.000000] signal: max sigframe size: 3632
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009efff] usable
[ 0.000000] BIOS-e820: [mem 0x000000000009f000-0x00000000000fffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000008df6efff] usable
[ 0.000000] BIOS-e820: [mem 0x000000008df6f000-0x0000000092f2efff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000092f2f000-0x0000000093b2efff] ACPI NVS
[ 0.000000] BIOS-e820: [mem 0x0000000093b2f000-0x0000000093bfefff] ACPI data
[ 0.000000] BIOS-e820: [mem 0x0000000093bff000-0x0000000093bfffff] usable
[ 0.000000] BIOS-e820: [mem 0x0000000093c00000-0x0000000097ffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000099400000-0x00000000995fffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000099e00000-0x00000000a07fffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000c0000000-0x00000000cfffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000fed20000-0x00000000fed7ffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000085f7fffff] usable
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] efi: EFI v2.70 by Lenovo
[ 0.000000] efi: ACPI=0x93bfe000 ACPI 2.0=0x93bfe014 SMBIOS=0x8fcbc000 SMBIOS 3.0=0x8fcaf000 TPMFinalLog=0x93990000 MEMATTR=0x8a432018 ESRT=0x87ec1c18 RNG=0x8fe2eb18 TPMEventLog=0x6cb68018
[ 0.000000] efi: seeding entropy pool
[ 0.000000] random: fast init done
[ 0.000000] SMBIOS 3.2.0 present.
[ 0.000000] DMI: LENOVO 20XXS3HC26/20XXS3HC26, BIOS N32ET75W (1.51 ) 12/02/2021
[ 0.000000] tsc: Detected 3000.000 MHz processor
[ 0.000000] tsc: Detected 2995.200 MHz TSC
[ 0.000006] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[ 0.000008] e820: remove [mem 0x000a0000-0x000fffff] usable
[ 0.000012] last_pfn = 0x85f800 max_arch_pfn = 0x400000000
[ 0.000157] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
[ 0.001195] last_pfn = 0x93c00 max_arch_pfn = 0x400000000
[ 0.011509] esrt: Reserving ESRT space from 0x0000000087ec1c18 to 0x0000000087ec1e30.
[ 0.011524] e820: update [mem 0x87ec1000-0x87ec1fff] usable ==> reserved
[ 0.011806] Using GB pages for direct mapping
[ 0.011941] Secure boot enabled
[ 0.011941] RAMDISK: [mem 0x65719000-0x6698bfff]
[ 0.011945] ACPI: Early table checksum verification disabled
[ 0.011949] ACPI: RSDP 0x0000000093BFE014 000024 (v02 LENOVO)
[ 0.011953] ACPI: XSDT 0x0000000093BFC188 00012C (v01 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.011958] ACPI: FACP 0x000000008FC9D000 000114 (v06 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.011964] ACPI: DSDT 0x000000008FC63000 0349A1 (v02 LENOVO ICL 00000002 01000013)
[ 0.011967] ACPI: FACS 0x000000009396F000 000040
[ 0.011970] ACPI: SSDT 0x000000008FD4A000 00255D (v02 LENOVO CpuSsdt 00003000 INTL 20191018)
[ 0.011973] ACPI: SSDT 0x000000008FD49000 00059B (v02 LENOVO CtdpB 00001000 INTL 20191018)
[ 0.011976] ACPI: SSDT 0x000000008FCCE000 00400C (v02 LENOVO DptfTabl 00001000 INTL 20191018)
[ 0.011978] ACPI: SSDT 0x000000008FCA1000 00060E (v02 LENOVO Tpm2Tabl 00001000 INTL 20191018)
[ 0.011981] ACPI: TPM2 0x000000008FCA0000 00004C (v04 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.011984] ACPI: SSDT 0x000000008FC9F000 000564 (v02 LENOVO PerfTune 00001000 INTL 20191018)
[ 0.011987] ACPI: ECDT 0x000000008FC9E000 000053 (v01 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.011990] ACPI: HPET 0x000000008FC9C000 000038 (v01 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.011992] ACPI: APIC 0x000000008FC9B000 00012C (v04 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.011995] ACPI: SSDT 0x000000008FC9A000 0000F2 (v02 LENOVO PID1Ssdt 00000010 INTL 20191018)
[ 0.011998] ACPI: SSDT 0x000000008FC98000 0014F8 (v02 LENOVO ProjSsdt 00000010 INTL 20191018)
[ 0.012001] ACPI: SSDT 0x000000008FC60000 0028C0 (v02 LENOVO TglU_Rvp 00001000 INTL 20191018)
[ 0.012004] ACPI: NHLT 0x000000008FC5E000 001B80 (v00 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012006] ACPI: SSDT 0x000000008FC5A000 002CD7 (v02 LENOVO SaSsdt 00003000 INTL 20191018)
[ 0.012009] ACPI: SSDT 0x000000008FC56000 003573 (v02 LENOVO IgfxSsdt 00003000 INTL 20191018)
[ 0.012012] ACPI: SSDT 0x000000008FC4A000 00B339 (v02 LENOVO TcssSsdt 00001000 INTL 20191018)
[ 0.012015] ACPI: LPIT 0x000000008FC48000 0000CC (v01 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012018] ACPI: WSMT 0x000000008FC47000 000028 (v01 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012020] ACPI: SSDT 0x000000008FC46000 00012A (v02 LENOVO TbtTypeC 00000000 INTL 20191018)
[ 0.012023] ACPI: DBGP 0x000000008FC45000 000034 (v01 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012026] ACPI: DBG2 0x000000008FC44000 000054 (v00 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012033] ACPI: POAT 0x000000008FC43000 000055 (v03 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012038] ACPI: SSDT 0x000000008FC2C000 000B9C (v02 LENOVO UsbCTabl 00001000 INTL 20191018)
[ 0.012040] ACPI: BATB 0x000000008FC2B000 00004A (v02 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012043] ACPI: DMAR 0x000000008E429000 0000B8 (v02 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012046] ACPI: MCFG 0x000000008E427000 00003C (v01 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012049] ACPI: SSDT 0x000000008E426000 000144 (v02 LENOVO ADebTabl 00001000 INTL 20191018)
[ 0.012052] ACPI: ASF! 0x000000008E425000 0000A0 (v32 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012054] ACPI: BGRT 0x000000008FC2D000 000038 (v01 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012058] ACPI: PTDT 0x000000008E424000 000986 (v00 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012060] ACPI: UEFI 0x00000000930C3000 00008A (v01 LENOVO TP-N32 00001510 PTEC 00000002)
[ 0.012063] ACPI: FPDT 0x000000008E423000 000034 (v01 LENOVO TP-N32 00001510 PTEC 00001510)
[ 0.012065] ACPI: Reserving FACP table memory at [mem 0x8fc9d000-0x8fc9d113]
[ 0.012068] ACPI: Reserving DSDT table memory at [mem 0x8fc63000-0x8fc979a0]
[ 0.012070] ACPI: Reserving FACS table memory at [mem 0x9396f000-0x9396f03f]
[ 0.012072] ACPI: Reserving SSDT table memory at [mem 0x8fd4a000-0x8fd4c55c]
[ 0.012074] ACPI: Reserving SSDT table memory at [mem 0x8fd49000-0x8fd4959a]
[ 0.012076] ACPI: Reserving SSDT table memory at [mem 0x8fcce000-0x8fcd200b]
[ 0.012077] ACPI: Reserving SSDT table memory at [mem 0x8fca1000-0x8fca160d]
[ 0.012079] ACPI: Reserving TPM2 table memory at [mem 0x8fca0000-0x8fca004b]
[ 0.012081] ACPI: Reserving SSDT table memory at [mem 0x8fc9f000-0x8fc9f563]
[ 0.012083] ACPI: Reserving ECDT table memory at [mem 0x8fc9e000-0x8fc9e052]
[ 0.012085] ACPI: Reserving HPET table memory at [mem 0x8fc9c000-0x8fc9c037]
[ 0.012087] ACPI: Reserving APIC table memory at [mem 0x8fc9b000-0x8fc9b12b]
[ 0.012089] ACPI: Reserving SSDT table memory at [mem 0x8fc9a000-0x8fc9a0f1]
[ 0.012091] ACPI: Reserving SSDT table memory at [mem 0x8fc98000-0x8fc994f7]
[ 0.012093] ACPI: Reserving SSDT table memory at [mem 0x8fc60000-0x8fc628bf]
[ 0.012095] ACPI: Reserving NHLT table memory at [mem 0x8fc5e000-0x8fc5fb7f]
[ 0.012097] ACPI: Reserving SSDT table memory at [mem 0x8fc5a000-0x8fc5ccd6]
[ 0.012098] ACPI: Reserving SSDT table memory at [mem 0x8fc56000-0x8fc59572]
[ 0.012100] ACPI: Reserving SSDT table memory at [mem 0x8fc4a000-0x8fc55338]
[ 0.012102] ACPI: Reserving LPIT table memory at [mem 0x8fc48000-0x8fc480cb]
[ 0.012104] ACPI: Reserving WSMT table memory at [mem 0x8fc47000-0x8fc47027]
[ 0.012106] ACPI: Reserving SSDT table memory at [mem 0x8fc46000-0x8fc46129]
[ 0.012108] ACPI: Reserving DBGP table memory at [mem 0x8fc45000-0x8fc45033]
[ 0.012110] ACPI: Reserving DBG2 table memory at [mem 0x8fc44000-0x8fc44053]
[ 0.012112] ACPI: Reserving POAT table memory at [mem 0x8fc43000-0x8fc43054]
[ 0.012114] ACPI: Reserving SSDT table memory at [mem 0x8fc2c000-0x8fc2cb9b]
[ 0.012116] ACPI: Reserving BATB table memory at [mem 0x8fc2b000-0x8fc2b049]
[ 0.012118] ACPI: Reserving DMAR table memory at [mem 0x8e429000-0x8e4290b7]
[ 0.012120] ACPI: Reserving MCFG table memory at [mem 0x8e427000-0x8e42703b]
[ 0.012122] ACPI: Reserving SSDT table memory at [mem 0x8e426000-0x8e426143]
[ 0.012124] ACPI: Reserving ASF! table memory at [mem 0x8e425000-0x8e42509f]
[ 0.012126] ACPI: Reserving BGRT table memory at [mem 0x8fc2d000-0x8fc2d037]
[ 0.012128] ACPI: Reserving PTDT table memory at [mem 0x8e424000-0x8e424985]
[ 0.012130] ACPI: Reserving UEFI table memory at [mem 0x930c3000-0x930c3089]
[ 0.012132] ACPI: Reserving FPDT table memory at [mem 0x8e423000-0x8e423033]
[ 0.012661] No NUMA configuration found
[ 0.012661] Faking a node at [mem 0x0000000000000000-0x000000085f7fffff]
[ 0.012670] NODE_DATA(0) allocated [mem 0x85f7fc000-0x85f7fffff]
[ 0.012716] Zone ranges:
[ 0.012717] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.012718] DMA32 [mem 0x0000000001000000-0x00000000ffffffff]
[ 0.012720] Normal [mem 0x0000000100000000-0x000000085f7fffff]
[ 0.012721] Device empty
[ 0.012722] Movable zone start for each node
[ 0.012723] Early memory node ranges
[ 0.012723] node 0: [mem 0x0000000000001000-0x000000000009efff]
[ 0.012724] node 0: [mem 0x0000000000100000-0x000000008df6efff]
[ 0.012725] node 0: [mem 0x0000000093bff000-0x0000000093bfffff]
[ 0.012726] node 0: [mem 0x0000000100000000-0x000000085f7fffff]
[ 0.012729] Initmem setup node 0 [mem 0x0000000000001000-0x000000085f7fffff]
[ 0.012733] On node 0, zone DMA: 1 pages in unavailable ranges
[ 0.012758] On node 0, zone DMA: 97 pages in unavailable ranges
[ 0.016312] On node 0, zone DMA32: 23696 pages in unavailable ranges
[ 0.056913] On node 0, zone Normal: 17408 pages in unavailable ranges
[ 0.056928] On node 0, zone Normal: 2048 pages in unavailable ranges
[ 0.056986] Reserving Intel graphics memory at [mem 0x9c800000-0xa07fffff]
[ 0.058165] ACPI: PM-Timer IO Port: 0x1808
[ 0.058171] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
[ 0.058173] ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1])
[ 0.058174] ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1])
[ 0.058174] ACPI: LAPIC_NMI (acpi_id[0x04] high edge lint[0x1])
[ 0.058175] ACPI: LAPIC_NMI (acpi_id[0x05] high edge lint[0x1])
[ 0.058176] ACPI: LAPIC_NMI (acpi_id[0x06] high edge lint[0x1])
[ 0.058176] ACPI: LAPIC_NMI (acpi_id[0x07] high edge lint[0x1])
[ 0.058177] ACPI: LAPIC_NMI (acpi_id[0x08] high edge lint[0x1])
[ 0.058177] ACPI: LAPIC_NMI (acpi_id[0x09] high edge lint[0x1])
[ 0.058178] ACPI: LAPIC_NMI (acpi_id[0x0a] high edge lint[0x1])
[ 0.058178] ACPI: LAPIC_NMI (acpi_id[0x0b] high edge lint[0x1])
[ 0.058179] ACPI: LAPIC_NMI (acpi_id[0x0c] high edge lint[0x1])
[ 0.058179] ACPI: LAPIC_NMI (acpi_id[0x0d] high edge lint[0x1])
[ 0.058180] ACPI: LAPIC_NMI (acpi_id[0x0e] high edge lint[0x1])
[ 0.058181] ACPI: LAPIC_NMI (acpi_id[0x0f] high edge lint[0x1])
[ 0.058181] ACPI: LAPIC_NMI (acpi_id[0x10] high edge lint[0x1])
[ 0.058300] IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-119
[ 0.058303] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.058304] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.058308] ACPI: Using ACPI (MADT) for SMP configuration information
[ 0.058309] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[ 0.058331] e820: update [mem 0x88e80000-0x88f10fff] usable ==> reserved
[ 0.058338] TSC deadline timer available
[ 0.058338] smpboot: Allowing 8 CPUs, 0 hotplug CPUs
[ 0.058369] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
[ 0.058372] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]
[ 0.058375] PM: hibernation: Registered nosave memory: [mem 0x87ec1000-0x87ec1fff]
[ 0.058378] PM: hibernation: Registered nosave memory: [mem 0x88e80000-0x88f10fff]
[ 0.058380] PM: hibernation: Registered nosave memory: [mem 0x8df6f000-0x92f2efff]
[ 0.058381] PM: hibernation: Registered nosave memory: [mem 0x92f2f000-0x93b2efff]
[ 0.058382] PM: hibernation: Registered nosave memory: [mem 0x93b2f000-0x93bfefff]
[ 0.058384] PM: hibernation: Registered nosave memory: [mem 0x93c00000-0x97ffffff]
[ 0.058385] PM: hibernation: Registered nosave memory: [mem 0x98000000-0x993fffff]
[ 0.058385] PM: hibernation: Registered nosave memory: [mem 0x99400000-0x995fffff]
[ 0.058386] PM: hibernation: Registered nosave memory: [mem 0x99600000-0x99dfffff]
[ 0.058387] PM: hibernation: Registered nosave memory: [mem 0x99e00000-0xa07fffff]
[ 0.058387] PM: hibernation: Registered nosave memory: [mem 0xa0800000-0xbfffffff]
[ 0.058388] PM: hibernation: Registered nosave memory: [mem 0xc0000000-0xcfffffff]
[ 0.058388] PM: hibernation: Registered nosave memory: [mem 0xd0000000-0xfed1ffff]
[ 0.058389] PM: hibernation: Registered nosave memory: [mem 0xfed20000-0xfed7ffff]
[ 0.058390] PM: hibernation: Registered nosave memory: [mem 0xfed80000-0xffffffff]
[ 0.058391] [mem 0xd0000000-0xfed1ffff] available for PCI devices
[ 0.058392] Booting paravirtualized kernel on bare hardware
[ 0.058394] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370452778343963 ns
[ 0.063525] setup_percpu: NR_CPUS:320 nr_cpumask_bits:320 nr_cpu_ids:8 nr_node_ids:1
[ 0.063735] percpu: Embedded 62 pages/cpu s217088 r8192 d28672 u262144
[ 0.063745] pcpu-alloc: s217088 r8192 d28672 u262144 alloc=1*2097152
[ 0.063748] pcpu-alloc: [0] 0 1 2 3 4 5 6 7
[ 0.063777] Fallback order for Node 0: 0
[ 0.063780] Built 1 zonelists, mobility grouping on. Total pages: 8182546
[ 0.063781] Policy zone: Normal
[ 0.063782] Kernel command line: root=/dev/mapper/cryptroot rootflags=subvol=/root init=/usr/lib/systemd/systemd cryptdevice=/dev/disk/by-uuid/xxxx-xxxx-xxxx-xxxx-xxxx:cryptroot:allow-discards rw quiet
[ 0.063831] Unknown kernel command line parameters "cryptdevice=/dev/disk/by-uuid/xxxx-xxxx-xxxx-xxxx-xxxx:cryptroot:allow-discards", will be passed to user space.
[ 0.065069] Dentry cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear)
[ 0.065638] Inode-cache hash table entries: 2097152 (order: 12, 16777216 bytes, linear)
[ 0.065794] mem auto-init: stack:byref_all(zero), heap alloc:on, heap free:off
[ 0.124880] Memory: 32440388K/33250360K available (14343K kernel code, 2092K rwdata, 10936K rodata, 1792K init, 3852K bss, 809712K reserved, 0K cma-reserved)
[ 0.124884] random: get_random_u64 called from __kmem_cache_create+0x2a/0x550 with crng_init=1
[ 0.124976] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1
[ 0.124985] ftrace: allocating 44931 entries in 176 pages
[ 0.132205] ftrace: allocated 176 pages with 3 groups
[ 0.132275] Dynamic Preempt: full
[ 0.132303] rcu: Preemptible hierarchical RCU implementation.
[ 0.132303] rcu: RCU restricting CPUs from NR_CPUS=320 to nr_cpu_ids=8.
[ 0.132304] rcu: RCU priority boosting: priority 1 delay 500 ms.
[ 0.132305] Trampoline variant of Tasks RCU enabled.
[ 0.132306] Rude variant of Tasks RCU enabled.
[ 0.132306] Tracing variant of Tasks RCU enabled.
[ 0.132307] rcu: RCU calculated value of scheduler-enlistment delay is 30 jiffies.
[ 0.132308] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8
[ 0.135937] NR_IRQS: 20736, nr_irqs: 2048, preallocated irqs: 16
[ 0.136890] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
[ 0.136971] random: crng init done (trusting CPU's manufacturer)
[ 0.137004] Console: colour dummy device 80x25
[ 0.137016] printk: console [tty0] enabled
[ 0.137033] ACPI: Core revision 20211217
[ 0.137288] hpet: HPET dysfunctional in PC10. Force disabled.
[ 0.137289] APIC: Switch to symmetric I/O mode setup
[ 0.137290] DMAR: Host address width 39
[ 0.137291] DMAR: DRHD base: 0x000000fed90000 flags: 0x0
[ 0.137296] DMAR: dmar0: reg_base_addr fed90000 ver 4:0 cap 1c0000c40660462 ecap 29a00f0505e
[ 0.137298] DMAR: DRHD base: 0x000000fed85000 flags: 0x0
[ 0.137302] DMAR: dmar1: reg_base_addr fed85000 ver 1:0 cap d2008c40660462 ecap f050da
[ 0.137304] DMAR: DRHD base: 0x000000fed86000 flags: 0x0
[ 0.137307] DMAR: dmar2: reg_base_addr fed86000 ver 1:0 cap d2008c40660462 ecap f050da
[ 0.137309] DMAR: DRHD base: 0x000000fed91000 flags: 0x1
[ 0.137314] DMAR: dmar3: reg_base_addr fed91000 ver 1:0 cap d2008c40660462 ecap f050da
[ 0.137315] DMAR: RMRR base: 0x0000009c000000 end: 0x000000a07fffff
[ 0.137318] DMAR-IR: IOAPIC id 2 under DRHD base 0xfed91000 IOMMU 3
[ 0.137319] DMAR-IR: HPET id 0 under DRHD base 0xfed91000
[ 0.137320] DMAR-IR: Queued invalidation will be enabled to support x2apic and Intr-remapping.
[ 0.143124] DMAR-IR: Enabled IRQ remapping in x2apic mode
[ 0.143126] x2apic enabled
[ 0.143196] Switched APIC routing to cluster x2apic.
[ 0.157724] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2b2c8ec87c7, max_idle_ns: 440795278598 ns
[ 0.157730] Calibrating delay loop (skipped), value calculated using timer frequency.. 5992.00 BogoMIPS (lpj=9984000)
[ 0.157732] pid_max: default: 32768 minimum: 301
[ 0.161061] LSM: Security Framework initializing
[ 0.161061] landlock: Up and running.
[ 0.161061] Yama: becoming mindful.
[ 0.161061] LSM support for eBPF active
[ 0.161061] Mount-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
[ 0.161061] Mountpoint-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
[ 0.161061] x86/tme: not enabled by BIOS
[ 0.161061] CPU0: Thermal monitoring enabled (TM1)
[ 0.161061] x86/cpu: User Mode Instruction Prevention (UMIP) activated
[ 0.161061] process: using mwait in idle threads
[ 0.161061] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.161061] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.161061] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[ 0.161061] Spectre V2 : Mitigation: Enhanced IBRS
[ 0.161061] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[ 0.161061] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
[ 0.161061] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
[ 0.161061] Freeing SMP alternatives memory: 36K
[ 0.161061] smpboot: Estimated ratio of average max frequency by base frequency (times 1024): 1467
[ 0.161061] smpboot: CPU0: 11th Gen Intel(R) Core(TM) i7-1185G7 @ 3.00GHz (family: 0x6, model: 0x8c, stepping: 0x1)
[ 0.161061] cblist_init_generic: Setting adjustable number of callback queues.
[ 0.161061] cblist_init_generic: Setting shift to 3 and lim to 1.
[ 0.161061] cblist_init_generic: Setting shift to 3 and lim to 1.
[ 0.161061] cblist_init_generic: Setting shift to 3 and lim to 1.
[ 0.161061] Performance Events: PEBS fmt4+-baseline, AnyThread deprecated, Icelake events, 32-deep LBR, full-width counters, Intel PMU driver.
[ 0.161061] ... version: 5
[ 0.161061] ... bit width: 48
[ 0.161061] ... generic registers: 8
[ 0.161061] ... value mask: 0000ffffffffffff
[ 0.161061] ... max period: 00007fffffffffff
[ 0.161061] ... fixed-purpose events: 4
[ 0.161061] ... event mask: 0001000f000000ff
[ 0.161061] rcu: Hierarchical SRCU implementation.
[ 0.161061] NMI watchdog: Enabled. Permanently consumes one hw-PMU counter.
[ 0.161061] smp: Bringing up secondary CPUs ...
[ 0.161061] x86: Booting SMP configuration:
[ 0.161061] .... node #0, CPUs: #1 #2 #3 #4 #5 #6 #7
[ 0.175082] smp: Brought up 1 node, 8 CPUs
[ 0.175082] smpboot: Max logical packages: 1
[ 0.175082] smpboot: Total of 8 processors activated (47942.00 BogoMIPS)
[ 0.178942] devtmpfs: initialized
[ 0.178942] x86/mm: Memory block size: 128MB
[ 0.179003] ACPI: PM: Registering ACPI NVS region [mem 0x92f2f000-0x93b2efff] (12582912 bytes)
[ 0.179003] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns
[ 0.179003] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)
[ 0.179003] pinctrl core: initialized pinctrl subsystem
[ 0.181063] PM: RTC time: 11:20:36, date: 2022-04-19
[ 0.181169] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[ 0.181558] DMA: preallocated 4096 KiB GFP_KERNEL pool for atomic allocations
[ 0.181877] DMA: preallocated 4096 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[ 0.182213] DMA: preallocated 4096 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[ 0.182226] audit: initializing netlink subsys (disabled)
[ 0.182232] audit: type=2000 audit(1650367235.023:1): state=initialized audit_enabled=0 res=1
[ 0.182232] thermal_sys: Registered thermal governor 'fair_share'
[ 0.182232] thermal_sys: Registered thermal governor 'bang_bang'
[ 0.182232] thermal_sys: Registered thermal governor 'step_wise'
[ 0.182232] thermal_sys: Registered thermal governor 'user_space'
[ 0.182232] thermal_sys: Registered thermal governor 'power_allocator'
[ 0.182232] cpuidle: using governor ladder
[ 0.182232] cpuidle: using governor menu
[ 0.182232] HugeTLB: can free 4094 vmemmap pages for hugepages-1048576kB
[ 0.182232] ACPI FADT declares the system doesn't support PCIe ASPM, so disable it
[ 0.182232] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[ 0.182232] PCI: MMCONFIG for domain 0000 [bus 00-79] at [mem 0xc0000000-0xc79fffff] (base 0xc0000000)
[ 0.182232] PCI: MMCONFIG at [mem 0xc0000000-0xc79fffff] reserved in E820
[ 0.182232] PCI: Using configuration type 1 for base access
[ 0.182232] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[ 0.182232] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
[ 0.184413] HugeTLB: can free 6 vmemmap pages for hugepages-2048kB
[ 0.184413] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[ 0.184413] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.184472] ACPI: Added _OSI(Module Device)
[ 0.184473] ACPI: Added _OSI(Processor Device)
[ 0.184474] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.184474] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.184475] ACPI: Added _OSI(Linux-Dell-Video)
[ 0.184476] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[ 0.184477] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[ 0.259925] ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored
[ 0.270678] ACPI: 15 ACPI AML tables successfully acquired and loaded
[ 0.271931] ACPI: EC: EC started
[ 0.271932] ACPI: EC: interrupt blocked
[ 0.273178] ACPI: EC: EC_CMD/EC_SC=0x66, EC_DATA=0x62
[ 0.273179] ACPI: EC: Boot ECDT EC used to handle transactions
[ 0.290169] ACPI: Dynamic OEM Table Load:
[ 0.290176] ACPI: SSDT 0xFFFF95F88027AE00 0001CB (v02 PmRef Cpu0Psd 00003000 INTL 20191018)
[ 0.290833] ACPI: \_SB_.PR00: _OSC native thermal LVT Acked
[ 0.291488] ACPI: Dynamic OEM Table Load:
[ 0.291493] ACPI: SSDT 0xFFFF95F881875800 000394 (v02 PmRef Cpu0Cst 00003001 INTL 20191018)
[ 0.292257] ACPI: Dynamic OEM Table Load:
[ 0.292262] ACPI: SSDT 0xFFFF95F881883000 000689 (v02 PmRef Cpu0Ist 00003000 INTL 20191018)
[ 0.293082] ACPI: Dynamic OEM Table Load:
[ 0.293086] ACPI: SSDT 0xFFFF95F881874000 00028B (v02 PmRef Cpu0Hwp 00003000 INTL 20191018)
[ 0.293971] ACPI: Dynamic OEM Table Load:
[ 0.293976] ACPI: SSDT 0xFFFF95F88012B000 0008E7 (v02 PmRef ApIst 00003000 INTL 20191018)
[ 0.294838] ACPI: Dynamic OEM Table Load:
[ 0.294842] ACPI: SSDT 0xFFFF95F881884800 00048A (v02 PmRef ApHwp 00003000 INTL 20191018)
[ 0.295623] ACPI: Dynamic OEM Table Load:
[ 0.295626] ACPI: SSDT 0xFFFF95F881881800 0004D4 (v02 PmRef ApPsd 00003000 INTL 20191018)
[ 0.296410] ACPI: Dynamic OEM Table Load:
[ 0.296414] ACPI: SSDT 0xFFFF95F881883800 00048A (v02 PmRef ApCst 00003000 INTL 20191018)
[ 0.299211] ACPI: Interpreter enabled
[ 0.299254] ACPI: PM: (supports S0 S4 S5)
[ 0.299254] ACPI: Using IOAPIC for interrupt routing
[ 0.299284] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.299697] ACPI: Enabled 9 GPEs in block 00 to 7F
[ 0.300472] ACPI: PM: Power Resource [PXP]
[ 0.302598] ACPI: PM: Power Resource [PUBS]
[ 0.306091] ACPI: PM: Power Resource [BTPR]
[ 0.307109] ACPI: PM: Power Resource [PXP]
[ 0.307330] ACPI: PM: Power Resource [MRST]
[ 0.307341] ACPI: PM: Power Resource [DRST]
[ 0.308141] ACPI: PM: Power Resource [PXP]
[ 0.312470] ACPI: PM: Power Resource [V0PR]
[ 0.312561] ACPI: PM: Power Resource [V1PR]
[ 0.312649] ACPI: PM: Power Resource [V2PR]
[ 0.314415] ACPI: PM: Power Resource [WRST]
[ 0.314575] ACPI: PM: Power Resource [TBT0]
[ 0.314602] ACPI: PM: Power Resource [TBT1]
[ 0.314625] ACPI: PM: Power Resource [D3C]
[ 0.549528] ACPI: PM: Power Resource [PIN]
[ 0.549541] ACPI: PM: Power Resource [PINP]
[ 0.549879] ACPI: PCI Root Bridge [PC00] (domain 0000 [bus 00-79])
[ 0.549883] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI EDR HPX-Type3]
[ 0.554324] acpi PNP0A08:00: _OSC: platform does not support [AER]
[ 0.562813] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug SHPCHotplug PME PCIeCapability LTR DPC]
[ 0.562814] acpi PNP0A08:00: FADT indicates ASPM is unsupported, using BIOS configuration
[ 0.566551] PCI host bridge to bus 0000:00
[ 0.566552] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 0.566553] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 0.566554] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[ 0.566555] pci_bus 0000:00: root bus resource [mem 0xa0800000-0xbfffffff window]
[ 0.566556] pci_bus 0000:00: root bus resource [mem 0x4000000000-0x7fffffffff window]
[ 0.566557] pci_bus 0000:00: root bus resource [bus 00-79]
[ 0.566621] pci 0000:00:00.0: [8086:9a14] type 00 class 0x060000
[ 0.566916] pci 0000:00:02.0: [8086:9a49] type 00 class 0x030000
[ 0.566923] pci 0000:00:02.0: reg 0x10: [mem 0x603c000000-0x603cffffff 64bit]
[ 0.566927] pci 0000:00:02.0: reg 0x18: [mem 0x4000000000-0x401fffffff 64bit pref]
[ 0.566931] pci 0000:00:02.0: reg 0x20: [io 0x3000-0x303f]
[ 0.566943] pci 0000:00:02.0: BAR 2: assigned to efifb
[ 0.566944] pci 0000:00:02.0: DMAR: Skip IOMMU disabling for graphics
[ 0.566946] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[ 0.566969] pci 0000:00:02.0: reg 0x344: [mem 0x00000000-0x00ffffff 64bit]
[ 0.566970] pci 0000:00:02.0: VF(n) BAR0 space: [mem 0x00000000-0x06ffffff 64bit] (contains BAR0 for 7 VFs)
[ 0.566973] pci 0000:00:02.0: reg 0x34c: [mem 0x00000000-0x1fffffff 64bit pref]
[ 0.566974] pci 0000:00:02.0: VF(n) BAR2 space: [mem 0x00000000-0xdfffffff 64bit pref] (contains BAR2 for 7 VFs)
[ 0.567111] pci 0000:00:04.0: [8086:9a03] type 00 class 0x118000
[ 0.567126] pci 0000:00:04.0: reg 0x10: [mem 0x603d180000-0x603d19ffff 64bit]
[ 0.567460] pci 0000:00:06.0: [8086:9a09] type 01 class 0x060400
[ 0.567528] pci 0000:00:06.0: PME# supported from D0 D3hot D3cold
[ 0.567554] pci 0000:00:06.0: PTM enabled (root), 4ns granularity
[ 0.568077] pci 0000:00:07.0: [8086:9a25] type 01 class 0x060400
[ 0.568143] pci 0000:00:07.0: PME# supported from D0 D3hot D3cold
[ 0.568165] pci 0000:00:07.0: DPC: RP PIO log size 0 is invalid
[ 0.568743] pci 0000:00:07.2: [8086:9a27] type 01 class 0x060400
[ 0.568808] pci 0000:00:07.2: PME# supported from D0 D3hot D3cold
[ 0.568830] pci 0000:00:07.2: DPC: RP PIO log size 0 is invalid
[ 0.569463] pci 0000:00:08.0: [8086:9a11] type 00 class 0x088000
[ 0.569470] pci 0000:00:08.0: reg 0x10: [mem 0x603d1da000-0x603d1dafff 64bit]
[ 0.569560] pci 0000:00:0a.0: [8086:9a0d] type 00 class 0x118000
[ 0.569567] pci 0000:00:0a.0: reg 0x10: [mem 0x603d1c0000-0x603d1c7fff 64bit]
[ 0.569586] pci 0000:00:0a.0: enabling Extended Tags
[ 0.569684] pci 0000:00:0d.0: [8086:9a13] type 00 class 0x0c0330
[ 0.569695] pci 0000:00:0d.0: reg 0x10: [mem 0x603d1b0000-0x603d1bffff 64bit]
[ 0.569746] pci 0000:00:0d.0: PME# supported from D3hot D3cold
[ 0.570060] pci 0000:00:0d.2: [8086:9a1b] type 00 class 0x0c0340
[ 0.570070] pci 0000:00:0d.2: reg 0x10: [mem 0x603d140000-0x603d17ffff 64bit]
[ 0.570077] pci 0000:00:0d.2: reg 0x18: [mem 0x603d1d9000-0x603d1d9fff 64bit]
[ 0.570109] pci 0000:00:0d.2: supports D1 D2
[ 0.570109] pci 0000:00:0d.2: PME# supported from D0 D1 D2 D3hot D3cold
[ 0.570203] pci 0000:00:0d.3: [8086:9a1d] type 00 class 0x0c0340
[ 0.570213] pci 0000:00:0d.3: reg 0x10: [mem 0x603d100000-0x603d13ffff 64bit]
[ 0.570220] pci 0000:00:0d.3: reg 0x18: [mem 0x603d1d8000-0x603d1d8fff 64bit]
[ 0.570251] pci 0000:00:0d.3: supports D1 D2
[ 0.570252] pci 0000:00:0d.3: PME# supported from D0 D1 D2 D3hot D3cold
[ 0.570521] pci 0000:00:14.0: [8086:a0ed] type 00 class 0x0c0330
[ 0.570542] pci 0000:00:14.0: reg 0x10: [mem 0x603d1a0000-0x603d1affff 64bit]
[ 0.570645] pci 0000:00:14.0: PME# supported from D3hot D3cold
[ 0.570966] pci 0000:00:14.2: [8086:a0ef] type 00 class 0x050000
[ 0.570986] pci 0000:00:14.2: reg 0x10: [mem 0x603d1d0000-0x603d1d3fff 64bit]
[ 0.570999] pci 0000:00:14.2: reg 0x18: [mem 0x603d1d7000-0x603d1d7fff 64bit]
[ 0.571505] pci 0000:00:14.3: [8086:a0f0] type 00 class 0x028000
[ 0.571862] pci 0000:00:14.3: reg 0x10: [mem 0x603d1cc000-0x603d1cffff 64bit]
[ 0.573724] pci 0000:00:14.3: PME# supported from D0 D3hot D3cold
[ 0.575200] pci 0000:00:15.0: [8086:a0e8] type 00 class 0x0c8000
[ 0.576083] pci 0000:00:15.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[ 0.579963] pci 0000:00:16.0: [8086:a0e0] type 00 class 0x078000
[ 0.579986] pci 0000:00:16.0: reg 0x10: [mem 0x603d1d5000-0x603d1d5fff 64bit]
[ 0.580080] pci 0000:00:16.0: PME# supported from D3hot
[ 0.580583] pci 0000:00:1f.0: [8086:a082] type 00 class 0x060100
[ 0.580999] pci 0000:00:1f.3: [8086:a0c8] type 00 class 0x040380
[ 0.581067] pci 0000:00:1f.3: reg 0x10: [mem 0x603d1c8000-0x603d1cbfff 64bit]
[ 0.581164] pci 0000:00:1f.3: reg 0x20: [mem 0x603d000000-0x603d0fffff 64bit]
[ 0.581334] pci 0000:00:1f.3: PME# supported from D3hot D3cold
[ 0.581652] pci 0000:00:1f.4: [8086:a0a3] type 00 class 0x0c0500
[ 0.581678] pci 0000:00:1f.4: reg 0x10: [mem 0x603d1d4000-0x603d1d40ff 64bit]
[ 0.581704] pci 0000:00:1f.4: reg 0x20: [io 0xefa0-0xefbf]
[ 0.582029] pci 0000:00:1f.5: [8086:a0a4] type 00 class 0x0c8000
[ 0.582047] pci 0000:00:1f.5: reg 0x10: [mem 0xfe010000-0xfe010fff]
[ 0.582568] pci 0000:04:00.0: [1bb1:5018] type 00 class 0x010802
[ 0.582609] pci 0000:04:00.0: reg 0x10: [mem 0xbc200000-0xbc203fff 64bit]
[ 0.583487] pci 0000:00:06.0: PCI bridge to [bus 04]
[ 0.583490] pci 0000:00:06.0: bridge window [mem 0xbc200000-0xbc2fffff]
[ 0.583520] pci 0000:00:07.0: PCI bridge to [bus 20-49]
[ 0.583523] pci 0000:00:07.0: bridge window [mem 0xb0000000-0xbc1fffff]
[ 0.583526] pci 0000:00:07.0: bridge window [mem 0x6000000000-0x601bffffff 64bit pref]
[ 0.583552] pci 0000:00:07.2: PCI bridge to [bus 50-79]
[ 0.583556] pci 0000:00:07.2: bridge window [mem 0xa2000000-0xae1fffff]
[ 0.583559] pci 0000:00:07.2: bridge window [mem 0x6020000000-0x603bffffff 64bit pref]
[ 0.598231] ACPI: EC: interrupt unblocked
[ 0.598232] ACPI: EC: event unblocked
[ 0.598261] ACPI: EC: EC_CMD/EC_SC=0x66, EC_DATA=0x62
[ 0.598262] ACPI: EC: GPE=0x6e
[ 0.598262] ACPI: \_SB_.PC00.LPCB.EC__: Boot ECDT EC initialization complete
[ 0.598263] ACPI: \_SB_.PC00.LPCB.EC__: EC: Used to handle transactions and events
[ 0.598309] iommu: Default domain type: Translated
[ 0.598310] iommu: DMA domain TLB invalidation policy: lazy mode
[ 0.598318] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=mem,locks=none
[ 0.598318] pci 0000:00:02.0: vgaarb: bridge control possible
[ 0.598318] pci 0000:00:02.0: vgaarb: setting as boot device
[ 0.598318] vgaarb: loaded
[ 0.598318] SCSI subsystem initialized
[ 0.598318] libata version 3.00 loaded.
[ 0.598318] ACPI: bus type USB registered
[ 0.598318] usbcore: registered new interface driver usbfs
[ 0.598318] usbcore: registered new interface driver hub
[ 0.598318] usbcore: registered new device driver usb
[ 0.598318] pps_core: LinuxPPS API ver. 1 registered
[ 0.598318] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]>
[ 0.598318] PTP clock support registered
[ 0.598318] EDAC MC: Ver: 3.0.0
[ 0.598341] Registered efivars operations
[ 0.598341] NetLabel: Initializing
[ 0.598341] NetLabel: domain hash size = 128
[ 0.598341] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
[ 0.598341] NetLabel: unlabeled traffic allowed by default
[ 0.598341] mctp: management component transport protocol core
[ 0.598341] NET: Registered PF_MCTP protocol family
[ 0.598341] PCI: Using ACPI for IRQ routing
[ 0.620928] PCI: pci_cache_line_size set to 64 bytes
[ 0.621221] pci 0000:00:1f.5: can't claim BAR 0 [mem 0xfe010000-0xfe010fff]: no compatible bridge window
[ 0.621689] e820: reserve RAM buffer [mem 0x0009f000-0x0009ffff]
[ 0.621690] e820: reserve RAM buffer [mem 0x87ec1000-0x87ffffff]
[ 0.621690] e820: reserve RAM buffer [mem 0x88e80000-0x8bffffff]
[ 0.621691] e820: reserve RAM buffer [mem 0x8df6f000-0x8fffffff]
[ 0.621691] e820: reserve RAM buffer [mem 0x93c00000-0x93ffffff]
[ 0.621692] e820: reserve RAM buffer [mem 0x85f800000-0x85fffffff]
[ 0.621882] clocksource: Switched to clocksource tsc-early
[ 0.625453] VFS: Disk quotas dquot_6.6.0
[ 0.625460] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.625510] pnp: PnP ACPI init
[ 0.627704] system 00:00: [io 0x0680-0x069f] has been reserved
[ 0.627706] system 00:00: [io 0x164e-0x164f] has been reserved
[ 0.627795] system 00:01: [io 0x1854-0x1857] has been reserved
[ 0.627829] pnp 00:04: disabling [mem 0xc0000000-0xcfffffff] because it overlaps 0000:00:02.0 BAR 9 [mem 0x00000000-0xdfffffff 64bit pref]
[ 0.627849] system 00:04: [io 0x1800-0x189f] could not be reserved
[ 0.627850] system 00:04: [io 0x0800-0x087f] has been reserved
[ 0.627851] system 00:04: [io 0x0880-0x08ff] has been reserved
[ 0.627852] system 00:04: [io 0x0900-0x097f] has been reserved
[ 0.627852] system 00:04: [io 0x0980-0x09ff] has been reserved
[ 0.627853] system 00:04: [io 0x0a00-0x0a7f] has been reserved
[ 0.627854] system 00:04: [io 0x0a80-0x0aff] has been reserved
[ 0.627854] system 00:04: [io 0x0b00-0x0b7f] has been reserved
[ 0.627855] system 00:04: [io 0x0b80-0x0bff] has been reserved
[ 0.627856] system 00:04: [io 0x15e0-0x15ef] has been reserved
[ 0.627856] system 00:04: [io 0x1600-0x167f] could not be reserved
[ 0.627857] system 00:04: [io 0x1640-0x165f] could not be reserved
[ 0.627859] system 00:04: [mem 0xfed10000-0xfed13fff] has been reserved
[ 0.627860] system 00:04: [mem 0xfed18000-0xfed18fff] has been reserved
[ 0.627861] system 00:04: [mem 0xfed19000-0xfed19fff] has been reserved
[ 0.627862] system 00:04: [mem 0xfeb00000-0xfebfffff] has been reserved
[ 0.627862] system 00:04: [mem 0xfed20000-0xfed3ffff] has been reserved
[ 0.627863] system 00:04: [mem 0xfed90000-0xfed93fff] could not be reserved
[ 0.628399] pnp 00:05: disabling [mem 0xc0000000-0xcfffffff] because it overlaps 0000:00:02.0 BAR 9 [mem 0x00000000-0xdfffffff 64bit pref]
[ 0.628410] system 00:05: [mem 0xfedc0000-0xfedc7fff] has been reserved
[ 0.628411] system 00:05: [mem 0xfeda0000-0xfeda0fff] has been reserved
[ 0.628412] system 00:05: [mem 0xfeda1000-0xfeda1fff] has been reserved
[ 0.628413] system 00:05: [mem 0xfed20000-0xfed7ffff] could not be reserved
[ 0.628414] system 00:05: [mem 0xfed90000-0xfed93fff] could not be reserved
[ 0.628415] system 00:05: [mem 0xfed45000-0xfed8ffff] could not be reserved
[ 0.628416] system 00:05: [mem 0xfee00000-0xfeefffff] has been reserved
[ 0.628802] system 00:06: [io 0x1800-0x18fe] could not be reserved
[ 0.628803] system 00:06: [mem 0xfe000000-0xfe01ffff] has been reserved
[ 0.628804] system 00:06: [mem 0xfe04c000-0xfe04ffff] has been reserved
[ 0.628805] system 00:06: [mem 0xfe050000-0xfe0affff] has been reserved
[ 0.628806] system 00:06: [mem 0xfe0d0000-0xfe0fffff] has been reserved
[ 0.628807] system 00:06: [mem 0xfe200000-0xfe7fffff] has been reserved
[ 0.628808] system 00:06: [mem 0xff000000-0xffffffff] has been reserved
[ 0.628809] system 00:06: [mem 0xfd000000-0xfd68ffff] has been reserved
[ 0.628810] system 00:06: [mem 0xfd6b0000-0xfd6cffff] has been reserved
[ 0.628810] system 00:06: [mem 0xfd6f0000-0xfdffffff] has been reserved
[ 0.629029] system 00:07: [io 0x2000-0x20fe] has been reserved
[ 0.629398] pnp 00:09: disabling [mem 0x000c0000-0x000c3fff] because it overlaps 0000:00:02.0 BAR 6 [mem 0x000c0000-0x000dffff]
[ 0.629399] pnp 00:09: disabling [mem 0x000c8000-0x000cbfff] because it overlaps 0000:00:02.0 BAR 6 [mem 0x000c0000-0x000dffff]
[ 0.629400] pnp 00:09: disabling [mem 0x000d0000-0x000d3fff] because it overlaps 0000:00:02.0 BAR 6 [mem 0x000c0000-0x000dffff]
[ 0.629401] pnp 00:09: disabling [mem 0x000d8000-0x000dbfff] because it overlaps 0000:00:02.0 BAR 6 [mem 0x000c0000-0x000dffff]
[ 0.629402] pnp 00:09: disabling [mem 0x00000000-0x0009ffff] because it overlaps 0000:00:02.0 BAR 7 [mem 0x00000000-0x06ffffff 64bit]
[ 0.629403] pnp 00:09: disabling [mem 0x000c0000-0x000c3fff disabled] because it overlaps 0000:00:02.0 BAR 7 [mem 0x00000000-0x06ffffff 64bit]
[ 0.629404] pnp 00:09: disabling [mem 0x000c8000-0x000cbfff disabled] because it overlaps 0000:00:02.0 BAR 7 [mem 0x00000000-0x06ffffff 64bit]
[ 0.629405] pnp 00:09: disabling [mem 0x000d0000-0x000d3fff disabled] because it overlaps 0000:00:02.0 BAR 7 [mem 0x00000000-0x06ffffff 64bit]
[ 0.629406] pnp 00:09: disabling [mem 0x000d8000-0x000dbfff disabled] because it overlaps 0000:00:02.0 BAR 7 [mem 0x00000000-0x06ffffff 64bit]
[ 0.629407] pnp 00:09: disabling [mem 0x000e0000-0x000e3fff] because it overlaps 0000:00:02.0 BAR 7 [mem 0x00000000-0x06ffffff 64bit]
[ 0.629408] pnp 00:09: disabling [mem 0x000e8000-0x000ebfff] because it overlaps 0000:00:02.0 BAR 7 [mem 0x00000000-0x06ffffff 64bit]
[ 0.629409] pnp 00:09: disabling [mem 0x000f0000-0x000fffff] because it overlaps 0000:00:02.0 BAR 7 [mem 0x00000000-0x06ffffff 64bit]
[ 0.629409] pnp 00:09: disabling [mem 0x00100000-0xa07fffff] because it overlaps 0000:00:02.0 BAR 7 [mem 0x00000000-0x06ffffff 64bit]
[ 0.629410] pnp 00:09: disabling [mem 0x00000000-0x0009ffff disabled] because it overlaps 0000:00:02.0 BAR 9 [mem 0x00000000-0xdfffffff 64bit pref]
[ 0.629411] pnp 00:09: disabling [mem 0x000c0000-0x000c3fff disabled] because it overlaps 0000:00:02.0 BAR 9 [mem 0x00000000-0xdfffffff 64bit pref]
[ 0.629412] pnp 00:09: disabling [mem 0x000c8000-0x000cbfff disabled] because it overlaps 0000:00:02.0 BAR 9 [mem 0x00000000-0xdfffffff 64bit pref]
[ 0.629413] pnp 00:09: disabling [mem 0x000d0000-0x000d3fff disabled] because it overlaps 0000:00:02.0 BAR 9 [mem 0x00000000-0xdfffffff 64bit pref]
[ 0.629414] pnp 00:09: disabling [mem 0x000d8000-0x000dbfff disabled] because it overlaps 0000:00:02.0 BAR 9 [mem 0x00000000-0xdfffffff 64bit pref]
[ 0.629415] pnp 00:09: disabling [mem 0x000e0000-0x000e3fff disabled] because it overlaps 0000:00:02.0 BAR 9 [mem 0x00000000-0xdfffffff 64bit pref]
[ 0.629415] pnp 00:09: disabling [mem 0x000e8000-0x000ebfff disabled] because it overlaps 0000:00:02.0 BAR 9 [mem 0x00000000-0xdfffffff 64bit pref]
[ 0.629416] pnp 00:09: disabling [mem 0x000f0000-0x000fffff disabled] because it overlaps 0000:00:02.0 BAR 9 [mem 0x00000000-0xdfffffff 64bit pref]
[ 0.629417] pnp 00:09: disabling [mem 0x00100000-0xa07fffff disabled] because it overlaps 0000:00:02.0 BAR 9 [mem 0x00000000-0xdfffffff 64bit pref]
[ 0.629426] system 00:09: [mem 0xfec00000-0xfed3ffff] could not be reserved
[ 0.629427] system 00:09: [mem 0xfed4c000-0xffffffff] could not be reserved
[ 0.629643] pnp: PnP ACPI: found 10 devices
[ 0.634710] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[ 0.634740] NET: Registered PF_INET protocol family
[ 0.634896] IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[ 0.635846] tcp_listen_portaddr_hash hash table entries: 16384 (order: 6, 262144 bytes, linear)
[ 0.636025] TCP established hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[ 0.636257] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[ 0.636292] TCP: Hash tables configured (established 262144 bind 65536)
[ 0.636396] MPTCP token hash table entries: 32768 (order: 7, 786432 bytes, linear)
[ 0.636486] UDP hash table entries: 16384 (order: 7, 524288 bytes, linear)
[ 0.636548] UDP-Lite hash table entries: 16384 (order: 7, 524288 bytes, linear)
[ 0.636595] NET: Registered PF_UNIX/PF_LOCAL protocol family
[ 0.636599] NET: Registered PF_XDP protocol family
[ 0.636603] pci 0000:00:07.0: bridge window [io 0x1000-0x0fff] to [bus 20-49] add_size 1000
[ 0.636606] pci 0000:00:07.2: bridge window [io 0x1000-0x0fff] to [bus 50-79] add_size 1000
[ 0.636612] pci 0000:00:02.0: BAR 9: assigned [mem 0x4020000000-0x40ffffffff 64bit pref]
[ 0.636615] pci 0000:00:02.0: BAR 7: assigned [mem 0x4100000000-0x4106ffffff 64bit]
[ 0.636618] pci 0000:00:07.0: BAR 13: assigned [io 0x4000-0x4fff]
[ 0.636619] pci 0000:00:07.2: BAR 13: assigned [io 0x5000-0x5fff]
[ 0.636620] pci 0000:00:15.0: BAR 0: assigned [mem 0x4107000000-0x4107000fff 64bit]
[ 0.637049] pci 0000:00:1f.5: BAR 0: assigned [mem 0xa0800000-0xa0800fff]
[ 0.637077] pci 0000:00:06.0: PCI bridge to [bus 04]
[ 0.637083] pci 0000:00:06.0: bridge window [mem 0xbc200000-0xbc2fffff]
[ 0.637091] pci 0000:00:07.0: PCI bridge to [bus 20-49]
[ 0.637093] pci 0000:00:07.0: bridge window [io 0x4000-0x4fff]
[ 0.637095] pci 0000:00:07.0: bridge window [mem 0xb0000000-0xbc1fffff]
[ 0.637097] pci 0000:00:07.0: bridge window [mem 0x6000000000-0x601bffffff 64bit pref]
[ 0.637101] pci 0000:00:07.2: PCI bridge to [bus 50-79]
[ 0.637102] pci 0000:00:07.2: bridge window [io 0x5000-0x5fff]
[ 0.637104] pci 0000:00:07.2: bridge window [mem 0xa2000000-0xae1fffff]
[ 0.637106] pci 0000:00:07.2: bridge window [mem 0x6020000000-0x603bffffff 64bit pref]
[ 0.637111] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
[ 0.637111] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
[ 0.637112] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[ 0.637113] pci_bus 0000:00: resource 7 [mem 0xa0800000-0xbfffffff window]
[ 0.637113] pci_bus 0000:00: resource 8 [mem 0x4000000000-0x7fffffffff window]
[ 0.637114] pci_bus 0000:04: resource 1 [mem 0xbc200000-0xbc2fffff]
[ 0.637115] pci_bus 0000:20: resource 0 [io 0x4000-0x4fff]
[ 0.637116] pci_bus 0000:20: resource 1 [mem 0xb0000000-0xbc1fffff]
[ 0.637116] pci_bus 0000:20: resource 2 [mem 0x6000000000-0x601bffffff 64bit pref]
[ 0.637117] pci_bus 0000:50: resource 0 [io 0x5000-0x5fff]
[ 0.637118] pci_bus 0000:50: resource 1 [mem 0xa2000000-0xae1fffff]
[ 0.637118] pci_bus 0000:50: resource 2 [mem 0x6020000000-0x603bffffff 64bit pref]
[ 0.638019] PCI: CLS 0 bytes, default 64
[ 0.638026] DMAR: Intel-IOMMU force enabled due to platform opt in
[ 0.638034] DMAR: No ATSR found
[ 0.638034] DMAR: No SATC found
[ 0.638035] DMAR: IOMMU feature fl1gp_support inconsistent
[ 0.638036] DMAR: IOMMU feature pgsel_inv inconsistent
[ 0.638037] DMAR: IOMMU feature nwfs inconsistent
[ 0.638037] DMAR: IOMMU feature dit inconsistent
[ 0.638037] DMAR: IOMMU feature sc_support inconsistent
[ 0.638038] DMAR: IOMMU feature dev_iotlb_support inconsistent
[ 0.638038] DMAR: dmar2: Using Queued invalidation
[ 0.638041] DMAR: dmar1: Using Queued invalidation
[ 0.638043] DMAR: dmar0: Using Queued invalidation
[ 0.638044] DMAR: dmar3: Using Queued invalidation
[ 0.638070] Trying to unpack rootfs image as initramfs...
[ 0.638165] pci 0000:00:00.0: Adding to iommu group 0
[ 0.638170] pci 0000:00:02.0: Adding to iommu group 1
[ 0.638176] pci 0000:00:04.0: Adding to iommu group 2
[ 0.638186] pci 0000:00:06.0: Adding to iommu group 3
[ 0.638192] pci 0000:00:07.0: Adding to iommu group 4
[ 0.638198] pci 0000:00:07.2: Adding to iommu group 5
[ 0.638203] pci 0000:00:08.0: Adding to iommu group 6
[ 0.638208] pci 0000:00:0a.0: Adding to iommu group 7
[ 0.638216] pci 0000:00:0d.0: Adding to iommu group 8
[ 0.638221] pci 0000:00:0d.2: Adding to iommu group 8
[ 0.638225] pci 0000:00:0d.3: Adding to iommu group 8
[ 0.638233] pci 0000:00:14.0: Adding to iommu group 9
[ 0.638237] pci 0000:00:14.2: Adding to iommu group 9
[ 0.638242] pci 0000:00:14.3: Adding to iommu group 10
[ 0.638247] pci 0000:00:15.0: Adding to iommu group 11
[ 0.638254] pci 0000:00:16.0: Adding to iommu group 12
[ 0.638263] pci 0000:00:1f.0: Adding to iommu group 13
[ 0.638268] pci 0000:00:1f.3: Adding to iommu group 13
[ 0.638273] pci 0000:00:1f.4: Adding to iommu group 13
[ 0.638278] pci 0000:00:1f.5: Adding to iommu group 13
[ 0.638287] pci 0000:04:00.0: Adding to iommu group 14
[ 0.638379] DMAR: Intel(R) Virtualization Technology for Directed I/O
[ 0.638380] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[ 0.638381] software IO TLB: mapped [mem 0x0000000083ce2000-0x0000000087ce2000] (64MB)
[ 0.638403] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2b2c8ec87c7, max_idle_ns: 440795278598 ns
[ 0.638436] clocksource: Switched to clocksource tsc
[ 0.638449] platform rtc_cmos: registered platform RTC device (no PNP device found)
[ 0.644949] Initialise system trusted keyrings
[ 0.644956] Key type blacklist registered
[ 0.644985] workingset: timestamp_bits=41 max_order=23 bucket_order=0
[ 0.645555] zbud: loaded
[ 0.651949] Key type asymmetric registered
[ 0.651951] Asymmetric key parser 'x509' registered
[ 0.682763] Freeing initrd memory: 18892K
[ 0.684030] alg: self-tests for CTR-KDF (hmac(sha256)) passed
[ 0.684038] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)
[ 0.684055] io scheduler mq-deadline registered
[ 0.684055] io scheduler kyber registered
[ 0.684066] io scheduler bfq registered
[ 0.809476] pcieport 0000:00:06.0: PME: Signaling with IRQ 124
[ 0.809884] pcieport 0000:00:07.0: PME: Signaling with IRQ 125
[ 0.809917] pcieport 0000:00:07.0: pciehp: Slot #0 AttnBtn- PwrCtrl- MRL- AttnInd- PwrInd- HotPlug+ Surprise+ Interlock- NoCompl+ IbPresDis- LLActRep+
[ 0.810386] pcieport 0000:00:07.2: PME: Signaling with IRQ 126
[ 0.810413] pcieport 0000:00:07.2: pciehp: Slot #0 AttnBtn- PwrCtrl- MRL- AttnInd- PwrInd- HotPlug+ Surprise+ Interlock- NoCompl+ IbPresDis- LLActRep+
[ 0.810599] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[ 0.811111] Monitor-Mwait will be used to enter C-1 state
[ 0.811118] Monitor-Mwait will be used to enter C-2 state
[ 0.811123] Monitor-Mwait will be used to enter C-3 state
[ 0.811127] ACPI: \_SB_.PR00: Found 3 idle states
[ 0.812700] ACPI: AC: AC Adapter [AC] (off-line)
[ 0.812803] input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input0
[ 0.814530] ACPI: button: Sleep Button [SLPB]
[ 0.814611] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input1
[ 0.817807] ACPI: button: Lid Switch [LID]
[ 0.817848] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input2
[ 0.817872] ACPI: button: Power Button [PWRB]
[ 0.818211] smpboot: Estimated ratio of average max frequency by base frequency (times 1024): 1467
[ 0.833954] thermal LNXTHERM:00: registered as thermal_zone0
[ 0.833956] ACPI: thermal: Thermal Zone [THM0] (51 C)
[ 0.834069] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[ 0.835070] hpet_acpi_add: no address or irqs in _CRS
[ 0.835090] Non-volatile memory driver v1.3
[ 0.835090] Linux agpgart interface v0.103
[ 0.835091] AMD-Vi: AMD IOMMUv2 functionality not available on this system - This is not a bug.
[ 0.835669] ACPI: bus type drm_connector registered
[ 0.837049] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 0.837051] ehci-pci: EHCI PCI platform driver
[ 0.837054] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 0.837055] ohci-pci: OHCI PCI platform driver
[ 0.837058] uhci_hcd: USB Universal Host Controller Interface driver
[ 0.837076] usbcore: registered new interface driver usbserial_generic
[ 0.837078] usbserial: USB Serial support registered for generic
[ 0.837255] rtc_cmos rtc_cmos: RTC can wake from S4
[ 0.839385] rtc_cmos rtc_cmos: registered as rtc0
[ 0.839837] rtc_cmos rtc_cmos: setting system clock to 2022-04-19T11:20:36 UTC (1650367236)
[ 0.839844] rtc_cmos rtc_cmos: alarms up to one month, y3k, 114 bytes nvram
[ 0.840418] intel_pstate: Intel P-state driver initializing
[ 0.842028] intel_pstate: HWP enabled
[ 0.842881] ledtrig-cpu: registered to indicate activity on CPUs
[ 0.843028] efifb: probing for efifb
[ 0.843040] efifb: showing boot graphics
[ 0.844603] efifb: framebuffer at 0x4000000000, using 9000k, total 9000k
[ 0.844604] efifb: mode is 1920x1200x32, linelength=7680, pages=1
[ 0.844605] efifb: scrolling: redraw
[ 0.844605] efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
[ 0.844625] fbcon: Deferring console take-over
[ 0.844626] fb0: EFI VGA frame buffer device
[ 0.844654] hid: raw HID events driver (C) Jiri Kosina
[ 0.844728] intel_pmc_core INT33A1:00: initialized
[ 0.844758] drop_monitor: Initializing network drop monitor service
[ 0.844793] Initializing XFRM netlink socket
[ 0.844841] NET: Registered PF_INET6 protocol family
[ 0.848302] Segment Routing with IPv6
[ 0.848303] RPL Segment Routing with IPv6
[ 0.848308] In-situ OAM (IOAM) with IPv6
[ 0.848329] NET: Registered PF_PACKET protocol family
[ 0.849351] microcode: sig=0x806c1, pf=0x80, revision=0x9a
[ 0.850363] microcode: Microcode Update Driver: v2.2.
[ 0.852967] resctrl: L2 allocation detected
[ 0.852972] IPI shorthand broadcast: enabled
[ 0.852979] sched_clock: Marking stable (831691890, 20948342)->(869420930, -16780698)
[ 0.858090] registered taskstats version 1
[ 0.859247] Loading compiled-in X.509 certificates
[ 0.861118] Loaded X.509 cert 'Build time autogenerated kernel key: e41b103d992e7165164c88f6deb03f61f089d7db'
[ 0.861465] zswap: loaded using pool lz4/z3fold
[ 0.861859] Key type ._fscrypt registered
[ 0.861860] Key type .fscrypt registered
[ 0.861860] Key type fscrypt-provisioning registered
[ 0.864611] PM: Magic number: 2:851:327
[ 0.864831] RAS: Correctable Errors collector initialized.
[ 0.969790] ACPI: battery: Slot [BAT0] (battery present)
[ 0.977323] Freeing unused decrypted memory: 2036K
[ 0.977694] Freeing unused kernel image (initmem) memory: 1792K
[ 0.977695] Write protecting the kernel read-only data: 28672k
[ 0.978176] Freeing unused kernel image (text/rodata gap) memory: 2040K
[ 0.978500] Freeing unused kernel image (rodata/data gap) memory: 1352K
[ 0.984002] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[ 0.984005] rodata_test: all tests were successful
[ 0.984008] Run /init as init process
[ 0.984009] with arguments:
[ 0.984009] /init
[ 0.984010] with environment:
[ 0.984010] HOME=/
[ 0.984010] TERM=linux
[ 0.984011] cryptdevice=/dev/disk/by-uuid/xxxx-xxxx-xxxx-xxxx-xxxx:cryptroot:allow-discards
[ 1.069379] systemd[1]: systemd 250.4-2-arch running in system mode (+PAM +AUDIT -SELINUX -APPARMOR -IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK +XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified)
[ 1.084650] systemd[1]: Detected architecture x86-64.
[ 1.084655] systemd[1]: Running in initial RAM disk.
[ 1.084718] systemd[1]: Initializing machine ID from random generator.
[ 1.101742] systemd[1]: Queued start job for default target Initrd Default Target.
[ 1.102523] systemd[1]: Created slice Slice /system/systemd-cryptsetup.
[ 1.102565] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ 1.102586] systemd[1]: Reached target Local File Systems.
[ 1.102591] systemd[1]: Reached target Path Units.
[ 1.102597] systemd[1]: Reached target Slice Units.
[ 1.102603] systemd[1]: Reached target Swaps.
[ 1.102610] systemd[1]: Reached target Timer Units.
[ 1.102664] systemd[1]: Listening on Journal Audit Socket.
[ 1.102700] systemd[1]: Listening on Journal Socket (/dev/log).
[ 1.102733] systemd[1]: Listening on Journal Socket.
[ 1.102768] systemd[1]: Listening on udev Control Socket.
[ 1.102788] systemd[1]: Listening on udev Kernel Socket.
[ 1.102796] systemd[1]: Reached target Socket Units.
[ 1.103097] systemd[1]: Starting Create List of Static Device Nodes...
[ 1.103769] systemd[1]: Starting Journal Service...
[ 1.104076] systemd[1]: Starting Load Kernel Modules...
[ 1.104371] systemd[1]: Starting Coldplug All udev Devices...
[ 1.104595] systemd[1]: Finished Create List of Static Device Nodes.
[ 1.104964] systemd[1]: Starting Create Static Device Nodes in /dev...
[ 1.106003] systemd[1]: Finished Load Kernel Modules.
[ 1.106855] systemd[1]: Finished Create Static Device Nodes in /dev.
[ 1.107442] systemd[1]: Starting Rule-based Manager for Device Events and Files...
[ 1.114042] systemd[1]: Started Rule-based Manager for Device Events and Files.
[ 1.114079] audit: type=1130 audit(1650367236.769:2): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udevd comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success'
[ 1.122524] systemd[1]: Started Journal Service.
[ 1.122557] audit: type=1130 audit(1650367236.779:3): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success'
[ 1.145272] audit: type=1130 audit(1650367236.803:4): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udev-trigger comm="systemd" exe="/init" hostname=? addr=? terminal=? res=success'
[ 1.149500] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[ 1.149811] i8042: Warning: Keylock active
[ 1.151563] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 1.151574] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 1.163158] xhci_hcd 0000:00:0d.0: xHCI Host Controller
[ 1.163165] xhci_hcd 0000:00:0d.0: new USB bus registered, assigned bus number 1
[ 1.164234] xhci_hcd 0000:00:0d.0: hcc params 0x20007fc1 hci version 0x120 quirks 0x0000000200009810
[ 1.164401] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.17
[ 1.164403] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.164405] usb usb1: Product: xHCI Host Controller
[ 1.164406] usb usb1: Manufacturer: Linux 5.17.3-arch1-1 xhci-hcd
[ 1.164407] usb usb1: SerialNumber: 0000:00:0d.0
[ 1.165714] hub 1-0:1.0: USB hub found
[ 1.165723] hub 1-0:1.0: 1 port detected
[ 1.166151] xhci_hcd 0000:00:0d.0: xHCI Host Controller
[ 1.166153] xhci_hcd 0000:00:0d.0: new USB bus registered, assigned bus number 2
[ 1.166156] xhci_hcd 0000:00:0d.0: Host supports USB 3.1 Enhanced SuperSpeed
[ 1.166190] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.17
[ 1.166192] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.166193] usb usb2: Product: xHCI Host Controller
[ 1.166194] usb usb2: Manufacturer: Linux 5.17.3-arch1-1 xhci-hcd
[ 1.166195] usb usb2: SerialNumber: 0000:00:0d.0
[ 1.167451] hub 2-0:1.0: USB hub found
[ 1.167461] hub 2-0:1.0: 4 ports detected
[ 1.168561] usb: port power management may be unreliable
[ 1.168874] xhci_hcd 0000:00:14.0: xHCI Host Controller
[ 1.168878] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 3
[ 1.170057] xhci_hcd 0000:00:14.0: hcc params 0x20007fc1 hci version 0x120 quirks 0x0000000000009810
[ 1.170337] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.17
[ 1.170340] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.170342] usb usb3: Product: xHCI Host Controller
[ 1.170343] usb usb3: Manufacturer: Linux 5.17.3-arch1-1 xhci-hcd
[ 1.170344] usb usb3: SerialNumber: 0000:00:14.0
[ 1.170455] hub 3-0:1.0: USB hub found
[ 1.170494] hub 3-0:1.0: 12 ports detected
[ 1.171386] nvme 0000:04:00.0: platform quirk: setting simple suspend
[ 1.171478] nvme nvme0: pci function 0000:04:00.0
[ 1.172289] xhci_hcd 0000:00:14.0: xHCI Host Controller
[ 1.172292] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 4
[ 1.172293] xhci_hcd 0000:00:14.0: Host supports USB 3.1 Enhanced SuperSpeed
[ 1.172354] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.17
[ 1.172355] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.172356] usb usb4: Product: xHCI Host Controller
[ 1.172357] usb usb4: Manufacturer: Linux 5.17.3-arch1-1 xhci-hcd
[ 1.172358] usb usb4: SerialNumber: 0000:00:14.0
[ 1.172410] hub 4-0:1.0: USB hub found
[ 1.172440] hub 4-0:1.0: 4 ports detected
[ 1.172859] cryptd: max_cpu_qlen set to 1000
[ 1.176611] nvme nvme0: missing or invalid SUBNQN field.
[ 1.176639] nvme nvme0: Shutdown timeout set to 10 seconds
[ 1.178486] nvme nvme0: 8/0/0 default/read/poll queues
[ 1.178523] tpm_tis STM0125:00: 2.0 TPM (device-id 0x0, rev-id 78)
[ 1.180007] nvme0n1: p1 p2
[ 1.188736] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3
[ 1.190726] AVX2 version of gcm_enc/dec engaged.
[ 1.190759] AES CTR mode by8 optimization enabled
[ 1.316020] device-mapper: uevent: version 1.0.3
[ 1.316154] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: [email protected]
[ 1.424416] usb 3-3: new full-speed USB device number 2 using xhci_hcd
[ 1.566969] usb 3-3: New USB device found, idVendor=06cb, idProduct=00fc, bcdDevice= 0.00
[ 1.566977] usb 3-3: New USB device strings: Mfr=0, Product=0, SerialNumber=1
[ 1.566980] usb 3-3: SerialNumber: 7b571cd95cd2
[ 1.691087] usb 3-4: new high-speed USB device number 3 using xhci_hcd
[ 1.834296] usb 3-4: New USB device found, idVendor=04f2, idProduct=b6ea, bcdDevice=62.18
[ 1.834301] usb 3-4: New USB device strings: Mfr=3, Product=1, SerialNumber=2
[ 1.834302] usb 3-4: Product: Integrated Camera
[ 1.834303] usb 3-4: Manufacturer: Chicony Electronics Co.,Ltd.
[ 1.834304] usb 3-4: SerialNumber: 0001
[ 1.957785] usb 3-7: new full-speed USB device number 4 using xhci_hcd
[ 2.100909] usb 3-7: New USB device found, idVendor=1050, idProduct=0407, bcdDevice= 5.12
[ 2.100917] usb 3-7: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 2.100919] usb 3-7: Product: YubiKey OTP+FIDO+CCID
[ 2.100921] usb 3-7: Manufacturer: Yubico
[ 2.112072] input: Yubico YubiKey OTP+FIDO+CCID as /devices/pci0000:00/0000:00:14.0/usb3/3-7/3-7:1.0/0003:1050:0407.0001/input/input5
[ 2.168212] hid-generic 0003:1050:0407.0001: input,hidraw0: USB HID v1.10 Keyboard [Yubico YubiKey OTP+FIDO+CCID] on usb-0000:00:14.0-7/input0
[ 2.169628] hid-generic 0003:1050:0407.0002: hiddev96,hidraw1: USB HID v1.10 Device [Yubico YubiKey OTP+FIDO+CCID] on usb-0000:00:14.0-7/input1
[ 2.169670] usbcore: registered new interface driver usbhid
[ 2.169672] usbhid: USB HID core driver
[ 2.227948] usb 3-10: new full-speed USB device number 5 using xhci_hcd
[ 2.348972] Key type trusted registered
[ 2.355349] Key type encrypted registered
[ 2.359677] audit: type=1338 audit(1650367238.016:5): module=crypt op=ctr ppid=1 pid=242 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-cryptse" exe="/usr/lib/systemd/systemd-cryptsetup" dev=254:0 error_msg='success' res=1
[ 2.359772] audit: type=1300 audit(1650367238.016:5): arch=c000003e syscall=16 success=yes exit=0 a0=4 a1=c138fd09 a2=55d13e02d660 a3=7ffd54b9eab2 items=6 ppid=1 pid=242 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-cryptse" exe="/usr/lib/systemd/systemd-cryptsetup" key=(null)
[ 2.359774] audit: type=1307 audit(1650367238.016:5): cwd="/"
[ 2.359775] audit: type=1302 audit(1650367238.016:5): item=0 name=(null) inode=38 dev=00:07 mode=040755 ouid=0 ogid=0 rdev=00:00 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
[ 2.359776] audit: type=1302 audit(1650367238.016:5): item=1 name=(null) inode=13402 dev=00:07 mode=040755 ouid=0 ogid=0 rdev=00:00 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
[ 2.359778] audit: type=1302 audit(1650367238.016:5): item=2 name=(null) inode=27 dev=00:07 mode=040755 ouid=0 ogid=0 rdev=00:00 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
[ 2.370510] raid6: skipped pq benchmark and selected avx512x4
[ 2.370511] raid6: using avx512x2 recovery algorithm
[ 2.372602] xor: automatically using best checksumming function avx
[ 2.376214] usb 3-10: New USB device found, idVendor=8087, idProduct=0026, bcdDevice= 0.02
[ 2.376217] usb 3-10: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 2.399980] Btrfs loaded, crc32c=crc32c-intel, zoned=yes, fsverity=yes
[ 2.400494] BTRFS: device fsid 4f5e23f0-3f92-46e9-bc0b-7e986256ce7b devid 1 transid 138155 /dev/dm-0 scanned by systemd-udevd (207)
[ 2.475365] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 2.475369] BTRFS info (device dm-0): using free space tree
[ 2.475370] BTRFS info (device dm-0): has skinny extents
[ 2.559252] BTRFS info (device dm-0): enabling ssd optimizations
[ 2.694297] systemd-journald[171]: Received SIGTERM from PID 1 (systemd).
[ 2.758486] systemd[1]: systemd 250.4-2-arch running in system mode (+PAM +AUDIT -SELINUX -APPARMOR -IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK +XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified)
[ 2.774747] systemd[1]: Detected architecture x86-64.
[ 2.775428] systemd[1]: Hostname set to <alrua-x1>.
[ 3.862415] systemd[1]: initrd-switch-root.service: Deactivated successfully.
[ 2.862464] systemd[1]: Stopped Switch Root.
[ 2.862592] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
[ 2.862733] systemd[1]: Created slice Slice /system/getty.
[ 2.862847] systemd[1]: Created slice Slice /system/loadkeys.
[ 2.862951] systemd[1]: Created slice Slice /system/modprobe.
[ 2.863057] systemd[1]: Created slice Slice /system/netns.
[ 2.863187] systemd[1]: Created slice Slice /system/openvpn-client.
[ 2.863302] systemd[1]: Created slice Slice /system/rfkill-block.
[ 2.863409] systemd[1]: Created slice Slice /system/syncthing.
[ 2.863486] systemd[1]: Created slice User and Session Slice.
[ 2.863514] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ 2.863531] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ 2.863602] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[ 2.863614] systemd[1]: Reached target Local Encrypted Volumes.
[ 2.863625] systemd[1]: Stopped target Switch Root.
[ 2.863631] systemd[1]: Stopped target Initrd File Systems.
[ 2.863635] systemd[1]: Stopped target Initrd Root File System.
[ 2.863642] systemd[1]: Reached target Local Integrity Protected Volumes.
[ 2.863656] systemd[1]: Reached target Remote File Systems.
[ 2.863663] systemd[1]: Reached target Slice Units.
[ 2.863670] systemd[1]: Reached target Swaps.
[ 2.863680] systemd[1]: Reached target Local Verity Protected Volumes.
[ 2.863707] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[ 2.863985] systemd[1]: Listening on LVM2 poll daemon socket.
[ 2.865652] systemd[1]: Listening on RPCbind Server Activation Socket.
[ 2.865669] systemd[1]: Reached target RPC Port Mapper.
[ 2.866132] systemd[1]: Listening on Process Core Dump Socket.
[ 2.866185] systemd[1]: Listening on Network Service Netlink Socket.
[ 2.866676] systemd[1]: Listening on udev Control Socket.
[ 2.866708] systemd[1]: Listening on udev Kernel Socket.
[ 2.867107] systemd[1]: Mounting Huge Pages File System...
[ 2.867425] systemd[1]: Mounting POSIX Message Queue File System...
[ 2.867754] systemd[1]: Mounting Kernel Debug File System...
[ 2.868086] systemd[1]: Mounting Kernel Trace File System...
[ 2.868140] systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
[ 2.868555] systemd[1]: Mounting Temporary Directory /tmp...
[ 2.869107] systemd[1]: Starting Create List of Static Device Nodes...
[ 2.869522] systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
[ 2.869943] systemd[1]: Starting Load Kernel Module configfs...
[ 2.870288] systemd[1]: Starting Load Kernel Module drm...
[ 2.870632] systemd[1]: Starting Load Kernel Module fuse...
[ 2.870711] systemd[1]: [email protected]: Deactivated successfully.
[ 2.870739] systemd[1]: Stopped [email protected].
[ 2.870801] systemd[1]: systemd-fsck-root.service: Deactivated successfully.
[ 2.870824] systemd[1]: Stopped File System Check on Root Device.
[ 2.870872] systemd[1]: Stopped Journal Service.
[ 2.871721] systemd[1]: Starting Journal Service...
[ 2.872740] systemd[1]: Starting Load Kernel Modules...
[ 2.873241] systemd[1]: Starting Remount Root and Kernel File Systems...
[ 2.873283] systemd[1]: Repartition Root Disk was skipped because all trigger condition checks failed.
[ 2.873752] systemd[1]: Starting Coldplug All udev Devices...
[ 2.874607] systemd[1]: Mounted Huge Pages File System.
[ 2.874660] systemd[1]: Mounted POSIX Message Queue File System.
[ 2.874698] systemd[1]: Mounted Kernel Debug File System.
[ 2.874734] systemd[1]: Mounted Kernel Trace File System.
[ 2.874770] systemd[1]: Mounted Temporary Directory /tmp.
[ 2.874876] systemd[1]: Finished Create List of Static Device Nodes.
[ 2.875011] systemd[1]: [email protected]: Deactivated successfully.
[ 2.875077] systemd[1]: Finished Load Kernel Module configfs.
[ 2.875206] systemd[1]: [email protected]: Deactivated successfully.
[ 2.875267] systemd[1]: Finished Load Kernel Module drm.
[ 2.875706] systemd[1]: Mounting Kernel Configuration File System...
[ 2.877722] fuse: init (API version 7.36)
[ 2.878065] systemd[1]: [email protected]: Deactivated successfully.
[ 2.878132] systemd[1]: Finished Load Kernel Module fuse.
[ 2.878481] systemd[1]: Mounting FUSE Control File System...
[ 2.883845] systemd[1]: Mounted Kernel Configuration File System.
[ 2.883896] systemd[1]: Mounted FUSE Control File System.
[ 2.939090] systemd[1]: Started Journal Service.
[ 2.939689] IPMI message handler: version 39.2
[ 2.941538] BTRFS info (device dm-0): turning on async discard
[ 2.941541] BTRFS info (device dm-0): use zstd compression, level 3
[ 2.941543] BTRFS info (device dm-0): using free space tree
[ 2.942205] ipmi device interface
[ 2.945025] Asymmetric key parser 'pkcs8' registered
[ 2.946613] systemd-journald[346]: Received client request to flush runtime journal.
[ 2.947721] vboxdrv: loading out-of-tree module taints kernel.
[ 2.947852] vboxdrv: module verification failed: signature and/or required key missing - tainting kernel
[ 2.949246] vboxdrv: Found 8 processor cores
[ 2.971329] vboxdrv: TSC mode is Invariant, tentative frequency 2995190714 Hz
[ 2.971332] vboxdrv: Successfully loaded version 6.1.32 r149290 (interface 0x00320000)
[ 2.972419] VBoxNetAdp: Successfully started.
[ 2.973501] VBoxNetFlt: Successfully started.
[ 2.986856] FAT-fs (nvme0n1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[ 3.322330] Userspace governor deprecated: use thermal netlink notification instead
[ 3.325131] input: Intel HID events as /devices/platform/INTC1051:00/input/input6
[ 3.637226] acpi PNP0C14:01: duplicate WMI GUID 05901221-D566-11D1-B2F0-00A0C9062910 (first instance was on PNP0C14:00)
[ 3.637348] acpi PNP0C14:02: duplicate WMI GUID 05901221-D566-11D1-B2F0-00A0C9062910 (first instance was on PNP0C14:00)
[ 3.637698] acpi PNP0C14:03: duplicate WMI GUID 05901221-D566-11D1-B2F0-00A0C9062910 (first instance was on PNP0C14:00)
[ 3.638658] acpi PNP0C14:04: duplicate WMI GUID 05901221-D566-11D1-B2F0-00A0C9062910 (first instance was on PNP0C14:00)
[ 3.639604] acpi PNP0C14:05: duplicate WMI GUID 05901221-D566-11D1-B2F0-00A0C9062910 (first instance was on PNP0C14:00)
[ 3.640447] thinkpad_acpi: ThinkPad ACPI Extras v0.26
[ 3.640454] thinkpad_acpi: http://ibm-acpi.sf.net/
[ 3.640456] thinkpad_acpi: ThinkPad BIOS N32ET75W (1.51 ), EC N32HT52W
[ 3.640458] thinkpad_acpi: Lenovo ThinkPad X1 Carbon Gen 9, model 20XXS3HC26
[ 3.640912] acpi PNP0C14:06: duplicate WMI GUID 05901221-D566-11D1-B2F0-00A0C9062910 (first instance was on PNP0C14:00)
[ 3.642532] acpi PNP0C14:07: duplicate WMI GUID 05901221-D566-11D1-B2F0-00A0C9062910 (first instance was on PNP0C14:00)
[ 3.642664] thinkpad_acpi: radio switch found; radios are enabled
[ 3.643309] thinkpad_acpi: This ThinkPad has standard ACPI backlight brightness control, supported by the ACPI video driver
[ 3.643312] thinkpad_acpi: Disabling thinkpad-acpi brightness events by default...
[ 3.648230] thinkpad_acpi: rfkill switch tpacpi_bluetooth_sw: radio is unblocked
[ 3.675457] thinkpad_acpi: battery 1 registered (start 0, stop 100, behaviours: 0x7)
[ 3.675468] ACPI: battery: new extension: ThinkPad Battery Extension
[ 3.681971] input: ThinkPad Extra Buttons as /devices/platform/thinkpad_acpi/input/input7
[ 3.684918] EDAC igen6: v2.5
[ 3.754065] ACPI: bus type thunderbolt registered
[ 3.853481] proc_thermal 0000:00:04.0: enabling device (0000 -> 0002)
[ 3.854327] Setting dangerous option enable_guc - tainting kernel
[ 3.854330] Setting dangerous option enable_fbc - tainting kernel
[ 3.854576] i915 0000:00:02.0: enabling device (0006 -> 0007)
[ 3.855068] i915 0000:00:02.0: [drm] VT-d active for gfx access
[ 3.855070] checking generic (4000000000 8ca000) vs hw (603c000000 1000000)
[ 3.855071] checking generic (4000000000 8ca000) vs hw (4000000000 20000000)
[ 3.855072] fb0: switching to i915 from EFI VGA
[ 3.855153] i915 0000:00:02.0: vgaarb: deactivate vga console
[ 3.855187] i915 0000:00:02.0: [drm] Transparent Hugepage mode 'huge=within_size'
[ 3.857177] i915 0000:00:02.0: vgaarb: changed VGA decodes: olddecodes=io+mem,decodes=io+mem:owns=mem
[ 3.859865] intel_rapl_common: Found RAPL domain package
[ 3.861751] i915 0000:00:02.0: [drm] Finished loading DMC firmware i915/tgl_dmc_ver2_12.bin (v2.12)
[ 3.927088] usb usb3-port10: disabled by hub (EMI?), re-enabling...
[ 3.927094] usb 3-10: USB disconnect, device number 5
[ 4.042709] intel-lpss 0000:00:15.0: enabling device (0000 -> 0002)
[ 4.043469] idma64 idma64.0: Found Intel integrated DMA 64-bit
[ 4.046144] mei_me 0000:00:16.0: enabling device (0000 -> 0002)
[ 4.079430] i801_smbus 0000:00:1f.4: enabling device (0000 -> 0003)
[ 4.079698] i801_smbus 0000:00:1f.4: SPD Write Disable is set
[ 4.079771] i801_smbus 0000:00:1f.4: SMBus using PCI interrupt
[ 4.081033] i2c i2c-10: 8/8 memory slots populated (from DMI)
[ 4.081035] i2c i2c-10: Systems with more than 4 memory slots not supported yet, not instantiating SPD
[ 4.082985] i915 0000:00:02.0: [drm] GuC firmware i915/tgl_guc_62.0.0.bin version 62.0 submission:disabled
[ 4.082989] i915 0000:00:02.0: [drm] GuC SLPC: disabled
[ 4.082990] i915 0000:00:02.0: [drm] HuC firmware i915/tgl_huc_7.9.3.bin version 7.9 authenticated:yes
[ 4.084795] i915 0000:00:02.0: [drm] Protected Xe Path (PXP) protected content support initialized
[ 4.089587] [drm] Initialized i915 1.6.0 20201103 for 0000:00:02.0 on minor 0
[ 4.092572] ACPI: video: Video Device [GFX0] (multi-head: yes rom: no post: no)
[ 4.092837] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input8
[ 4.095641] fbcon: i915drmfb (fb0) is primary device
[ 4.095643] fbcon: Deferring console take-over
[ 4.095645] i915 0000:00:02.0: [drm] fb0: i915drmfb frame buffer device
[ 4.152102] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[ 4.154190] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[ 4.213799] input: PC Speaker as /devices/platform/pcspkr/input/input10
[ 4.217723] input: SYNA8008:00 06CB:CE58 Mouse as /devices/pci0000:00/0000:00:15.0/i2c_designware.0/i2c-9/i2c-SYNA8008:00/0018:06CB:CE58.0003/input/input11
[ 4.217808] input: SYNA8008:00 06CB:CE58 Touchpad as /devices/pci0000:00/0000:00:15.0/i2c_designware.0/i2c-9/i2c-SYNA8008:00/0018:06CB:CE58.0003/input/input12
[ 4.217842] hid-generic 0018:06CB:CE58.0003: input,hidraw2: I2C HID v1.00 Mouse [SYNA8008:00 06CB:CE58] on i2c-SYNA8008:00
[ 4.297502] resource sanity check: requesting [mem 0xfedc0000-0xfedcdfff], which spans more than pnp 00:05 [mem 0xfedc0000-0xfedc7fff]
[ 4.297508] caller __uncore_imc_init_box+0xc9/0x100 [intel_uncore] mapping multiple BARs
[ 4.299979] intel-spi 0000:00:1f.5: BFPT parsing failed. Please consider using SPI_NOR_SKIP_SFDP when declaring the flash
[ 4.299982] intel-spi 0000:00:1f.5: failed to locate the chip
[ 4.300026] intel-spi: probe of 0000:00:1f.5 failed with error -22
[ 4.305197] zram: Added device: zram0
[ 4.318708] typec port0: bound usb3-port5 (ops connector_ops)
[ 4.318719] typec port0: bound usb2-port2 (ops connector_ops)
[ 4.362371] Intel(R) Wireless WiFi driver for Linux
[ 4.364599] wireguard: WireGuard 1.0.0 loaded. See http://www.wireguard.com for information.
[ 4.364601] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <[email protected]>. All Rights Reserved.
[ 4.400110] iwlwifi 0000:00:14.3: enabling device (0000 -> 0002)
[ 4.458289] iwlwifi 0000:00:14.3: Direct firmware load for iwlwifi-QuZ-a0-hr-b0-69.ucode failed with error -2
[ 4.505425] iwlwifi 0000:00:14.3: api flags index 2 larger than supported by driver
[ 4.505443] iwlwifi 0000:00:14.3: TLV_FW_FSEQ_VERSION: FSEQ Version: 89.3.35.37
[ 4.506052] iwlwifi 0000:00:14.3: loaded firmware version 68.01d30b0c.0 QuZ-a0-hr-b0-68.ucode op_mode iwlmvm
[ 4.550434] zram0: detected capacity change from 0 to 2035848
[ 4.612716] BTRFS info (device dm-0): devid 1 device path /dev/mapper/cryptroot changed to /dev/dm-0 scanned by systemd-udevd (404)
[ 4.628698] BTRFS info (device dm-0): devid 1 device path /dev/dm-0 changed to /dev/mapper/cryptroot scanned by systemd-udevd (404)
[ 4.663607] intel_tcc_cooling: Programmable TCC Offset detected
[ 4.671319] snd_hda_intel 0000:00:1f.3: DSP detected with PCI class/subclass/prog-if info 0x040380
[ 4.671426] snd_hda_intel 0000:00:1f.3: Digital mics found on Skylake+ platform, using SOF driver
[ 4.697811] psmouse serio1: trackpoint: Elan TrackPoint firmware: 0x92, buttons: 3/3
[ 4.710568] input: TPPS/2 Elan TrackPoint as /devices/platform/i8042/serio1/input/input9
[ 4.751935] intel_rapl_msr: PL4 support detected.
[ 4.752585] intel_rapl_common: Found RAPL domain package
[ 4.752588] intel_rapl_common: Found RAPL domain core
[ 4.752590] intel_rapl_common: Found RAPL domain uncore
[ 4.752591] intel_rapl_common: Found RAPL domain psys
[ 4.752886] iwlwifi 0000:00:14.3: Detected Intel(R) Wi-Fi 6 AX201 160MHz, REV=0x351
[ 4.752992] thermal thermal_zone7: failed to read out thermal zone (-61)
[ 4.785397] mei_hdcp 0000:00:16.0-b638ab7e-94e2-4ea2-a552-d1c54b627f04: bound 0000:00:02.0 (ops i915_hdcp_component_ops [i915])
[ 4.833059] iTCO_vendor_support: vendor-support=0
[ 4.841576] Bluetooth: Core ver 2.22
[ 4.846695] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: bound 0000:00:02.0 (ops i915_pxp_tee_component_ops [i915])
[ 4.851919] NET: Registered PF_BLUETOOTH protocol family
[ 4.851921] Bluetooth: HCI device and connection manager initialized
[ 4.851926] Bluetooth: HCI socket layer initialized
[ 4.851928] Bluetooth: L2CAP socket layer initialized
[ 4.851931] Bluetooth: SCO socket layer initialized
[ 4.886190] iwlwifi 0000:00:14.3: Detected RF HR B3, rfid=0x10a100
[ 4.886473] iTCO_wdt iTCO_wdt: Found a Intel PCH TCO device (Version=6, TCOBASE=0x0400)
[ 4.888186] iTCO_wdt iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)
[ 4.925236] Adding 1017920k swap on /dev/zram0. Priority:32767 extents:1 across:1017920k SSDscFS
[ 4.927600] zram: Added device: zram1
[ 4.928710] zram1: detected capacity change from 0 to 2035848
[ 4.951225] iwlwifi 0000:00:14.3: base HW address: f4:a4:75:a3:7a:91
[ 5.011196] mousedev: PS/2 mouse device common for all mice
[ 5.026301] input: SYNA8008:00 06CB:CE58 Mouse as /devices/pci0000:00/0000:00:15.0/i2c_designware.0/i2c-9/i2c-SYNA8008:00/0018:06CB:CE58.0003/input/input14
[ 5.026374] input: SYNA8008:00 06CB:CE58 Touchpad as /devices/pci0000:00/0000:00:15.0/i2c_designware.0/i2c-9/i2c-SYNA8008:00/0018:06CB:CE58.0003/input/input15
[ 5.026430] hid-multitouch 0018:06CB:CE58.0003: input,hidraw2: I2C HID v1.00 Mouse [SYNA8008:00 06CB:CE58] on i2c-SYNA8008:00
[ 5.030592] usbcore: registered new interface driver btusb
[ 5.075305] typec port1: bound usb3-port6 (ops connector_ops)
[ 5.075316] typec port1: bound usb2-port3 (ops connector_ops)
[ 5.181209] kauditd_printk_skb: 196 callbacks suppressed
[ 5.181214] audit: type=1334 audit(1650367240.839:194): prog-id=69 op=LOAD
[ 5.186064] audit: type=1334 audit(1650367240.843:195): prog-id=70 op=LOAD
[ 5.187925] Adding 1017920k swap on /dev/zram1. Priority:32767 extents:1 across:1017920k SSDscFS
[ 5.188239] audit: type=1334 audit(1650367240.846:196): prog-id=71 op=LOAD
[ 5.189697] audit: type=1334 audit(1650367240.846:197): prog-id=72 op=LOAD
[ 5.190102] audit: type=1130 audit(1650367240.846:198): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=polipo comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 5.190915] audit: type=1334 audit(1650367240.846:199): prog-id=73 op=LOAD
[ 5.197226] audit: type=1130 audit(1650367240.853:200): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=sshd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 5.199088] audit: type=1130 audit(1650367240.856:201): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=syncthing@alrua comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 5.213214] zram: Added device: zram2
[ 5.213408] audit: type=1130 audit(1650367240.869:202): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=rpc-statd-notify comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 5.214041] audit: type=1130 audit(1650367240.869:203): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-user-sessions comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 5.215124] zram2: detected capacity change from 0 to 2035848
[ 5.268206] NET: Registered PF_ALG protocol family
[ 5.286361] mc: Linux media interface: v0.10
[ 5.364649] videodev: Linux video capture interface: v2.00
[ 5.483103] Adding 1017920k swap on /dev/zram2. Priority:32767 extents:1 across:1017920k SSDscFS
[ 5.490782] zram: Added device: zram3
[ 5.491940] zram3: detected capacity change from 0 to 2035848
[ 5.553322] sof-audio-pci-intel-tgl 0000:00:1f.3: DSP detected with PCI class/subclass/prog-if info 0x040380
[ 5.573986] sof-audio-pci-intel-tgl 0000:00:1f.3: Digital mics found on Skylake+ platform, using SOF driver
[ 5.580304] sof-audio-pci-intel-tgl 0000:00:1f.3: DSP detected with PCI class/subclass/prog-if 0x040380
[ 5.580806] sof-audio-pci-intel-tgl 0000:00:1f.3: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915])
[ 5.589821] sof-audio-pci-intel-tgl 0000:00:1f.3: use msi interrupt mode
[ 5.670107] sof-audio-pci-intel-tgl 0000:00:1f.3: hda codecs found, mask 5
[ 5.670113] sof-audio-pci-intel-tgl 0000:00:1f.3: using HDA machine driver skl_hda_dsp_generic now
[ 5.670118] sof-audio-pci-intel-tgl 0000:00:1f.3: DMICs detected in NHLT tables: 4
[ 5.672271] sof-audio-pci-intel-tgl 0000:00:1f.3: Firmware info: version 2:1:1-3964a
[ 5.672276] sof-audio-pci-intel-tgl 0000:00:1f.3: Firmware: ABI 3:21:0 Kernel ABI 3:18:0
[ 5.672279] sof-audio-pci-intel-tgl 0000:00:1f.3: warn: FW ABI is more recent than kernel
[ 5.672284] sof-audio-pci-intel-tgl 0000:00:1f.3: unknown sof_ext_man header type 3 size 0x30
[ 5.720157] usb 3-4: Found UVC 1.10 device Integrated Camera (04f2:b6ea)
[ 5.728119] input: Integrated Camera: Integrated C as /devices/pci0000:00/0000:00:14.0/usb3/3-4/3-4:1.0/input/input17
[ 5.728529] usb 3-4: Found UVC 1.50 device Integrated Camera (04f2:b6ea)
[ 5.729010] input: Integrated Camera: Integrated I as /devices/pci0000:00/0000:00:14.0/usb3/3-4/3-4:1.2/input/input18
[ 5.729275] usbcore: registered new interface driver uvcvideo
[ 5.769169] sof-audio-pci-intel-tgl 0000:00:1f.3: Firmware info: version 2:1:1-3964a
[ 5.769178] sof-audio-pci-intel-tgl 0000:00:1f.3: Firmware: ABI 3:21:0 Kernel ABI 3:18:0
[ 5.769180] sof-audio-pci-intel-tgl 0000:00:1f.3: warn: FW ABI is more recent than kernel
[ 5.785297] Adding 1017920k swap on /dev/zram3. Priority:32767 extents:1 across:1017920k SSDscFS
[ 5.792296] zram: Added device: zram4
[ 5.793566] zram4: detected capacity change from 0 to 2035848
[ 5.825464] sof-audio-pci-intel-tgl 0000:00:1f.3: Topology: ABI 3:21:0 Kernel ABI 3:18:0
[ 5.825471] sof-audio-pci-intel-tgl 0000:00:1f.3: warn: topology ABI is more recent than kernel
[ 5.826478] skl_hda_dsp_generic skl_hda_dsp_generic: ASoC: Parent card not yet available, widget card binding deferred
[ 5.861588] snd_hda_codec_realtek ehdaudio0D0: autoconfig for ALC287: line_outs=2 (0x14/0x17/0x0/0x0/0x0) type:speaker
[ 5.861595] snd_hda_codec_realtek ehdaudio0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[ 5.861597] snd_hda_codec_realtek ehdaudio0D0: hp_outs=1 (0x21/0x0/0x0/0x0/0x0)
[ 5.861600] snd_hda_codec_realtek ehdaudio0D0: mono: mono_out=0x0
[ 5.861601] snd_hda_codec_realtek ehdaudio0D0: inputs:
[ 5.861602] snd_hda_codec_realtek ehdaudio0D0: Mic=0x19
[ 5.948546] snd_hda_codec_realtek ehdaudio0D0: ASoC: sink widget AIF1TX overwritten
[ 5.948556] snd_hda_codec_realtek ehdaudio0D0: ASoC: source widget AIF1RX overwritten
[ 5.948716] skl_hda_dsp_generic skl_hda_dsp_generic: ASoC: sink widget hifi3 overwritten
[ 5.948720] skl_hda_dsp_generic skl_hda_dsp_generic: ASoC: sink widget hifi2 overwritten
[ 5.948725] skl_hda_dsp_generic skl_hda_dsp_generic: ASoC: sink widget hifi1 overwritten
[ 5.948728] skl_hda_dsp_generic skl_hda_dsp_generic: ASoC: source widget Codec Output Pin1 overwritten
[ 5.948731] skl_hda_dsp_generic skl_hda_dsp_generic: ASoC: sink widget Codec Input Pin1 overwritten
[ 5.948737] skl_hda_dsp_generic skl_hda_dsp_generic: ASoC: sink widget Analog Codec Playback overwritten
[ 5.948743] skl_hda_dsp_generic skl_hda_dsp_generic: ASoC: sink widget Digital Codec Playback overwritten
[ 5.948748] skl_hda_dsp_generic skl_hda_dsp_generic: ASoC: sink widget Alt Analog Codec Playback overwritten
[ 5.948755] skl_hda_dsp_generic skl_hda_dsp_generic: ASoC: source widget Analog Codec Capture overwritten
[ 5.948760] skl_hda_dsp_generic skl_hda_dsp_generic: ASoC: source widget Digital Codec Capture overwritten
[ 5.948766] skl_hda_dsp_generic skl_hda_dsp_generic: ASoC: source widget Alt Analog Codec Capture overwritten
[ 5.948779] skl_hda_dsp_generic skl_hda_dsp_generic: hda_dsp_hdmi_build_controls: no PCM in topology for HDMI converter 3
[ 5.948781] skl_hda_dsp_generic skl_hda_dsp_generic: hda_dsp_hdmi_build_controls: no PCM in topology for HDMI converter 4
[ 5.948783] skl_hda_dsp_generic skl_hda_dsp_generic: hda_dsp_hdmi_build_controls: no PCM in topology for HDMI converter 5
[ 5.948785] skl_hda_dsp_generic skl_hda_dsp_generic: hda_dsp_hdmi_build_controls: no PCM in topology for HDMI converter 6
[ 5.948787] skl_hda_dsp_generic skl_hda_dsp_generic: hda_dsp_hdmi_build_controls: no PCM in topology for HDMI converter 7
[ 5.948788] skl_hda_dsp_generic skl_hda_dsp_generic: hda_dsp_hdmi_build_controls: no PCM in topology for HDMI converter 8
[ 5.976682] input: sof-hda-dsp Mic as /devices/pci0000:00/0000:00:1f.3/skl_hda_dsp_generic/sound/card0/input19
[ 5.976778] input: sof-hda-dsp Headphone as /devices/pci0000:00/0000:00:1f.3/skl_hda_dsp_generic/sound/card0/input20
[ 5.977362] input: sof-hda-dsp HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1f.3/skl_hda_dsp_generic/sound/card0/input21
[ 5.977423] input: sof-hda-dsp HDMI/DP,pcm=4 as /devices/pci0000:00/0000:00:1f.3/skl_hda_dsp_generic/sound/card0/input22
[ 5.977455] input: sof-hda-dsp HDMI/DP,pcm=5 as /devices/pci0000:00/0000:00:1f.3/skl_hda_dsp_generic/sound/card0/input23
[ 6.113718] Adding 1017920k swap on /dev/zram4. Priority:32767 extents:1 across:1017920k SSDscFS
[ 6.126610] zram: Added device: zram5
[ 6.127933] zram5: detected capacity change from 0 to 2035848
[ 6.430793] Adding 1017920k swap on /dev/zram5. Priority:32767 extents:1 across:1017920k SSDscFS
[ 6.435794] zram: Added device: zram6
[ 6.437635] zram6: detected capacity change from 0 to 2035848
[ 6.700632] Adding 1017920k swap on /dev/zram6. Priority:32767 extents:1 across:1017920k SSDscFS
[ 6.704505] zram: Added device: zram7
[ 6.705803] zram7: detected capacity change from 0 to 2035848
[ 6.826028] wlan0: authenticate with 4c:60:de:ea:b8:58
[ 6.826043] wlan0: bad VHT capabilities, disabling VHT
[ 6.826044] wlan0: 80 MHz not supported, disabling VHT
[ 6.832644] wlan0: send auth to 4c:60:de:ea:b8:58 (try 1/3)
[ 6.859513] wlan0: authenticated
[ 6.864403] wlan0: associate with 4c:60:de:ea:b8:58 (try 1/3)
[ 6.866131] wlan0: RX AssocResp from 4c:60:de:ea:b8:58 (capab=0x431 status=0 aid=2)
[ 6.873864] wlan0: associated
[ 6.991026] Adding 1017920k swap on /dev/zram7. Priority:32767 extents:1 across:1017920k SSDscFS
[ 10.477842] kauditd_printk_skb: 244 callbacks suppressed
[ 10.477845] audit: type=1334 audit(1650367246.136:446): prog-id=0 op=UNLOAD
[ 10.477850] audit: type=1334 audit(1650367246.136:447): prog-id=0 op=UNLOAD
[ 10.477852] audit: type=1334 audit(1650367246.136:448): prog-id=0 op=UNLOAD
[ 10.477855] audit: type=1334 audit(1650367246.136:449): prog-id=0 op=UNLOAD
[ 10.477857] audit: type=1334 audit(1650367246.136:450): prog-id=0 op=UNLOAD
[ 10.477860] audit: type=1334 audit(1650367246.136:451): prog-id=0 op=UNLOAD
[ 10.477862] audit: type=1334 audit(1650367246.136:452): prog-id=0 op=UNLOAD
[ 10.477864] audit: type=1334 audit(1650367246.136:453): prog-id=0 op=UNLOAD
[ 10.477866] audit: type=1334 audit(1650367246.136:454): prog-id=0 op=UNLOAD
[ 10.477869] audit: type=1334 audit(1650367246.136:455): prog-id=0 op=UNLOAD
[ 10.499417] fbcon: Taking over console
[ 10.519406] Console: switching to colour frame buffer device 240x75
[ 15.357563] wlan0: disconnect from AP 4c:60:de:ea:b8:58 for new auth to 4c:60:de:ea:b8:5a
[ 15.445521] wlan0: authenticate with 4c:60:de:ea:b8:5a
[ 15.445542] wlan0: bad VHT capabilities, disabling VHT
[ 15.451251] wlan0: send auth to 4c:60:de:ea:b8:5a (try 1/3)
[ 15.479061] wlan0: authenticated
[ 15.479111] wlan0: VHT capa missing/short, disabling VHT/HE
[ 15.481221] wlan0: associate with 4c:60:de:ea:b8:5a (try 1/3)
[ 15.483186] wlan0: RX ReassocResp from 4c:60:de:ea:b8:5a (capab=0x11 status=0 aid=3)
[ 15.487392] wlan0: associated
[ 18.163950] kauditd_printk_skb: 9 callbacks suppressed
[ 18.163955] audit: type=1100 audit(1650367253.819:465): pid=1069 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="alrua" exe="/usr/bin/login" hostname=alrua-x1 addr=? terminal=/dev/tty1 res=success'
[ 18.164107] audit: type=1101 audit(1650367253.819:466): pid=1069 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="alrua" exe="/usr/bin/login" hostname=alrua-x1 addr=? terminal=/dev/tty1 res=success'
[ 18.164821] audit: type=1103 audit(1650367253.823:467): pid=1069 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="alrua" exe="/usr/bin/login" hostname=alrua-x1 addr=? terminal=/dev/tty1 res=success'
[ 18.164916] audit: type=1006 audit(1650367253.823:468): pid=1069 uid=0 old-auid=4294967295 auid=1000 tty=tty1 old-ses=4294967295 ses=2 res=1
[ 18.164925] audit: type=1300 audit(1650367253.823:468): arch=c000003e syscall=1 success=yes exit=4 a0=5 a1=7ffe88ebc820 a2=4 a3=0 items=0 ppid=1 pid=1069 auid=1000 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=100 fsgid=0 tty=tty1 ses=2 comm="login" exe="/usr/bin/login" key=(null)
[ 18.164929] audit: type=1327 audit(1650367253.823:468): proctitle=2F62696E2F6C6F67696E002D70002D2D
[ 18.178413] audit: type=1105 audit(1650367253.836:469): pid=1069 uid=0 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_loginuid,pam_keyinit,pam_systemd_home,pam_limits,pam_unix,pam_permit,pam_mail,pam_systemd,pam_env acct="alrua" exe="/usr/bin/login" hostname=alrua-x1 addr=? terminal=/dev/tty1 res=success'
[ 18.178499] audit: type=1110 audit(1650367253.836:470): pid=1069 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="alrua" exe="/usr/bin/login" hostname=alrua-x1 addr=? terminal=/dev/tty1 res=success'
[ 18.798844] tun: Universal TUN/TAP device driver, 1.6
[ 19.440933] audit: type=1130 audit(1650367255.098:471): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=rtkit-daemon comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 20.008303] audit: type=1130 audit(1650367255.677:472): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=bluetooth comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 20.014723] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 20.014725] Bluetooth: BNEP filters: protocol multicast
[ 20.014729] Bluetooth: BNEP socket layer initialized
[ 39.112761] kauditd_printk_skb: 7 callbacks suppressed
[ 39.112766] audit: type=1130 audit(1650367274.932:480): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=kill-scdaemon comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 39.112775] audit: type=1131 audit(1650367274.932:481): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=kill-scdaemon comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 39.169171] PM: suspend entry (s2idle)
[ 39.191854] Filesystems sync: 0.022 seconds
[ 39.261199] audit: type=1130 audit(1650367275.079:482): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=autorandr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 39.261211] audit: type=1131 audit(1650367275.079:483): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=autorandr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 39.278244] Freezing user space processes ... (elapsed 0.001 seconds) done.
[ 39.279971] OOM killer disabled.
[ 39.279972] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[ 39.281129] printk: Suspending console(s) (use no_console_suspend to debug)
[ 39.282288] wlan0: deauthenticating from 4c:60:de:ea:b8:5a by local choice (Reason: 3=DEAUTH_LEAVING)
[ 39.471272] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK from CSME on HOST_GOES_DOWN message
[ 39.471376] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the SAP_ME_MSG_CHECK_SHARED_AREA message -19
[ 39.992947] ACPI: EC: interrupt blocked
[ 44.455046] ACPI: EC: interrupt unblocked
[ 44.987409] nvme nvme0: Shutdown timeout set to 10 seconds
[ 44.988776] nvme nvme0: 8/0/0 default/read/poll queues
[ 45.102503] i915 0000:00:02.0: [drm] GuC firmware i915/tgl_guc_62.0.0.bin version 62.0 submission:disabled
[ 45.102508] i915 0000:00:02.0: [drm] GuC SLPC: disabled
[ 45.102509] i915 0000:00:02.0: [drm] HuC firmware i915/tgl_huc_7.9.3.bin version 7.9 authenticated:yes
[ 45.474077] OOM killer enabled.
[ 45.474079] Restarting tasks ...
[ 45.474278] usb 3-3: USB disconnect, device number 2
[ 45.477951] done.
[ 45.488574] mei_hdcp 0000:00:16.0-b638ab7e-94e2-4ea2-a552-d1c54b627f04: bound 0000:00:02.0 (ops i915_hdcp_component_ops [i915])
[ 45.489897] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: bound 0000:00:02.0 (ops i915_pxp_tee_component_ops [i915])
[ 45.490234] thermal thermal_zone7: failed to read out thermal zone (-61)
[ 45.558079] PM: suspend exit
[ 45.628544] usb 3-3: new full-speed USB device number 6 using xhci_hcd
[ 45.774171] usb 3-3: New USB device found, idVendor=06cb, idProduct=00fc, bcdDevice= 0.00
[ 45.774179] usb 3-3: New USB device strings: Mfr=0, Product=0, SerialNumber=1
[ 45.774182] usb 3-3: SerialNumber: 7b571cd95cd2
[ 45.822570] audit: type=1130 audit(1650367281.402:484): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 45.822581] audit: type=1131 audit(1650367281.402:485): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 45.824684] audit: type=1130 audit(1650367281.402:486): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=wg-reload comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 45.838867] audit: type=1131 audit(1650367281.418:487): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=wg-reload comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 49.278479] audit: type=1100 audit(1650367284.862:488): pid=2437 uid=1000 auid=1000 ses=2 msg='op=PAM:unix_chkpwd acct="alrua" exe="/usr/bin/unix_chkpwd" hostname=? addr=? terminal=? res=success'
[ 56.895620] audit: type=1131 audit(1650367292.483:489): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=iwd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 56.897628] audit: type=1334 audit(1650367292.486:490): prog-id=187 op=LOAD
[ 56.910135] audit: type=1130 audit(1650367292.496:491): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=iwd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 56.923952] audit: type=1334 audit(1650367292.513:492): prog-id=0 op=UNLOAD
[ 58.212917] wlan0: authenticate with 4c:60:de:ea:b8:58
[ 58.212938] wlan0: bad VHT capabilities, disabling VHT
[ 58.212940] wlan0: 80 MHz not supported, disabling VHT
[ 58.220279] wlan0: send auth to 4c:60:de:ea:b8:58 (try 1/3)
[ 58.247170] wlan0: authenticated
[ 58.250141] wlan0: associate with 4c:60:de:ea:b8:58 (try 1/3)
[ 58.252280] wlan0: RX AssocResp from 4c:60:de:ea:b8:58 (capab=0x431 status=0 aid=2)
[ 58.259536] wlan0: associated
[ 60.181028] audit: type=1100 audit(1650367295.770:493): pid=2459 uid=1000 auid=1000 ses=2 msg='op=PAM:authentication grantors=pam_faillock,pam_permit,pam_faillock acct="alrua" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[ 60.181034] audit: type=1101 audit(1650367295.770:494): pid=2459 uid=1000 auid=1000 ses=2 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="alrua" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[ 60.181595] audit: type=1110 audit(1650367295.770:495): pid=2459 uid=1000 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[ 60.183268] audit: type=1105 audit(1650367295.774:496): pid=2459 uid=1000 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_systemd_home,pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'

2022-04-21 13:55:50

by Toke Høiland-Jørgensen

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

"Grumbach, Emmanuel" <[email protected]> writes:

> removing Luca
>
> On Wed, 2022-04-20 at 11:49 +0200, Toke Høiland-Jørgensen wrote:
>
>
> <snip>
>
>> > >
>> >
>> > So you do get a SAP connection. Weird.
>> > Checking with the CSME guys.
>> > Can you get dynamic debug prints for iwlmei module?
>> > Best would be tracing with -e iwlmei_sap_cmd
>>
>> Sure! Recorded these while doing a suspend-restore, then restarting iwd:
>>
>>   kworker/u16:34-34555 [004] 46452.506238: iwlmei_sap_cmd:       sap_cmd Tx: type 1010 len 8 seq
>> 11
>>   kworker/u16:34-34555 [004] 46452.506243: iwlmei_me_msg:        ME message: Tx: type 3 seq 8
>>   kworker/u16:34-34555 [004] 46452.559407: iwlmei_sap_cmd:       sap_cmd Tx: type 1001 len 4 seq
>> 12
>>   kworker/u16:14-33311 [004] 46452.573077: iwlmei_sap_cmd:       sap_cmd Tx: type 1006 len 0 seq
>> 13
>>   kworker/u16:14-33311 [004] 46452.573078: iwlmei_me_msg:        ME message: Tx: type 3 seq 10
>>      kworker/4:0-34305 [004] 46452.706737: iwlmei_me_msg:        ME message: Tx: type 3 seq 11
>>    kworker/u16:8-34462 [000] 46457.939453: iwlmei_me_msg:        ME message: Tx: type 1 seq 1
>>      kworker/4:2-32196 [004] 46457.940910: iwlmei_me_msg:        ME message: Rx: type 2 seq 81
>>      kworker/4:2-32196 [004] 46457.940912: iwlmei_sap_cmd:       sap_cmd Tx: type 1013 len 0 seq 1
>>      kworker/4:2-32196 [004] 46457.940913: iwlmei_me_msg:        ME message: Tx: type 3 seq 2
>>      kworker/4:2-32196 [004] 46457.942056: iwlmei_me_msg:        ME message: Rx: type 3 seq 82
>>      kworker/4:2-32196 [004] 46457.942060: iwlmei_sap_cmd:       sap_cmd Rx: type 502 len 4 seq 1
>>      kworker/4:2-32196 [004] 46458.042205: iwlmei_me_msg:        ME message: Rx: type 3 seq 83
>>      kworker/4:2-32196 [004] 46458.042208: iwlmei_sap_cmd:       sap_cmd Rx: type 500 len 1896 seq
>> 2
>>      kworker/4:2-32196 [004] 46458.042209: iwlmei_sap_cmd:       sap_cmd Rx: type 512 len 64 seq 3
>>      kworker/4:2-32196 [004] 46458.042214: iwlmei_sap_cmd:       sap_cmd Rx: type 502 len 4 seq 4
>>              iwd-35396 [003] 46467.085642: iwlmei_sap_cmd:       sap_cmd Tx: type 1008 len 8 seq 2
>>              iwd-35396 [003] 46467.085645: iwlmei_me_msg:        ME message: Tx: type 3 seq 3
>>              iwd-35396 [003] 46467.102818: iwlmei_sap_cmd:       sap_cmd Tx: type 1008 len 8 seq 3
>>              iwd-35396 [003] 46467.103369: iwlmei_sap_cmd:       sap_cmd Tx: type 1016 len 20 seq
>> 4
>>              iwd-35396 [003] 46467.103842: iwlmei_sap_cmd:       sap_cmd Tx: type 1001 len 4 seq 5
>>              iwd-35396 [003] 46467.104689: iwlmei_sap_cmd:       sap_cmd Tx: type 1002 len 12 seq
>> 6
>
> can you please send the content of tracing unparsed?
> There is more binary data that I can use.

Sure! I'll send you the trace.dat file off-list (it's almost 10 MB).

> I definitely need to talk to the CSME guys, I can see that CSME is
> alive even if you have AMT disabled.

What is this CSME thing, anyway? And does this imply that the AMT is not
really disabling itself (there's also a "permanently disable" option in
BIOS that I have not dared pick..)

> Problem: it's holiday season here.

No worries, I can live with working around the issue in the meantime; I
guess I can get systemd to automatically restart iwd when returning from
suspend if I can just figure out the right incantation :)

-Toke

2022-04-22 19:52:12

by Grumbach, Emmanuel

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

On Tue, 2022-04-19 at 22:58 +0200, Toke Høiland-Jørgensen wrote:
> "Grumbach, Emmanuel" <[email protected]> writes:
>
> > On Tue, 2022-04-19 at 20:46 +0300, Grumbach, Emmanuel wrote:
> > > On Tue, 2022-04-19 at 13:33 +0200, Toke Høiland-Jørgensen wrote:
> > > > "Grumbach, Emmanuel" <[email protected]> writes:
> > > >
> > > > > On Tue, 2022-04-19 at 09:41 +0300, Grumbach, Emmanuel wrote:
> > > > > > Hi Toke,
> > > > > >
> > > > > > On Tue, 2022-04-19 at 06:24 +0000, Coelho, Luciano wrote:
> > > > > > > On Mon, 2022-04-18 at 13:36 +0200, Toke Høiland-Jørgensen wrote:
> > > > > > > > Hi Luca
> > > > > > >
> > > > > > > Hi Toke,
> > > > > > >
> > > > > > > > I've started seeing issues with my iwlwifi interface going away after
> > > > > > > > suspend. I get errors like these in dmesg:
> > > > > > > >
> > > > > > > > [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice
> > > > > > > > (Reason:
> > > > > > > > 3=DEAUTH_LEAVING)
> > > > > > > > [104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't
> > > > > > > > get
> > > > > > > > ACK
> > > > > > > > from
> > > > > > > > CSME on HOST_GOES_DOWN message
> > > > > > > > [104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to
> > > > > > > > send
> > > > > > > > the
> > > > > > > > SAP_ME_MSG_CHECK_SHARED_AREA message -19
> > > > > > > >
> > > > > > > > And when the host comes back up, there is no connectivity. Restarting
> > > > > > > > iwd fixes the problem.
> > > > > > > >
> > > > > > > > This is on a 5.17.3 kernel (Arch Linux distribution kernel), and lspci
> > > > > > > > says the WiFi device is an "Intel Corporation Wi-Fi 6 AX201".
> > > > > > > >
> > > > > > > > Any ideas? :)
> > > > > > >
> > > > > > > This seems to be related to iwlmei, so I added Emmanuel to the thread.
> > > > > > >
> > > > > >
> > > > > > Can we have the full dmesg output?
> > > > > > What NIC / platform do you have?
> > > > > > Do you have AMT configured in the BIOS?
> > > > > > Did you enable wireless operation in AMT?
> > > > >
> > > > > Ah - this is AX201, ok, but I still need the platform and thefull dmesg :-)
> > > >
> > > > It's a Lenovo ThinkPad X1 Carbon Gen 9, model 20XXS3HC26; AMT is set to
> > > > "Disabled" in the BIOS. I rebooted and did a suspend/wake cycle after
> > > > connecting to the WiFi network, which produced the dmesg below.
> > >
> > > Thanks.
> > > This is a Skylake platform and we didn't have a handshake with CSME which is not enabled. We
> > > are
> > > missing a check before we shut down iwlmei.
> >
> > Hm... no, that seems to be 11th generation... so TigerLake?
> > But still, the fix below seems relevant.
>
> Hmm, tried the patch and the message is still there (as is the bug)...
> The error code is different now, though:
>
> [   65.773731] wlan0: deauthenticating from 4c:60:de:ea:b8:5a by local choice (Reason:
> 3=DEAUTH_LEAVING)
> [   65.973767] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK from
> CSME on HOST_GOES_DOWN message
> [   65.973845] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the
> SAP_ME_MSG_CHECK_SHARED_AREA message -14
>

So you do get a SAP connection. Weird.
Checking with the CSME guys.
Can you get dynamic debug prints for iwlmei module?
Best would be tracing with -e iwlmei_sap_cmd

I might also need dynamic debgug prints from the mei bus driver but that will be for later.
Thanks for reporting and providing the data!

2022-08-12 16:20:46

by Joachim Breitner

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

Hello Emmanuel,

> suspend. I get errors like these in dmesg:
>
> [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by local choice (Reason: 3=DEAUTH_LEAVING)
> [104393.347775] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK from CSME on HOST_GOES_DOWN message
> [104393.347876] iwlmei 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the SAP_ME_MSG_CHECK_SHARED_AREA message -19
>
> And when the host comes back up, there is no connectivity. Restarting
> iwd fixes the problem.

I am hitting the same problem, on Kernel 5.19, with the same Intel Wi-
Fi 6 AX201 device. I reported this initially to iwd, but they said it
might be a kernel problem, so I found this thread.

Has there been a solution since?

Thanks,
Joachim


--
Joachim Breitner
[email protected]
http://www.joachim-breitner.de/

2022-08-13 21:04:17

by Grumbach, Emmanuel

[permalink] [raw]
Subject: RE: iwlwifi devices disappear after suspend on kernel 5.17



>
> Hello Emmanuel,
>
> > suspend. I get errors like these in dmesg:
> >
> > [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by
> > local choice (Reason: 3=DEAUTH_LEAVING) [104393.347775] iwlmei
> > 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK
> > from CSME on HOST_GOES_DOWN message [104393.347876] iwlmei
> > 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the
> > SAP_ME_MSG_CHECK_SHARED_AREA message -19
> >
> > And when the host comes back up, there is no connectivity. Restarting
> > iwd fixes the problem.
>
> I am hitting the same problem, on Kernel 5.19, with the same Intel Wi- Fi 6
> AX201 device. I reported this initially to iwd, but they said it might be a kernel
> problem, so I found this thread.
>
> Has there been a solution since?
>

I moved on to other things, so I'll let the other people reply on this.

Cheers

2022-08-14 14:13:28

by Greenman, Gregory

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

On Sat, 2022-08-13 at 21:03 +0000, Grumbach, Emmanuel wrote:
>
>
> >
> > Hello Emmanuel,
> >
> > > suspend. I get errors like these in dmesg:
> > >
> > > [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by
> > > local choice (Reason: 3=DEAUTH_LEAVING) [104393.347775] iwlmei
> > > 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK
> > > from CSME on HOST_GOES_DOWN message [104393.347876] iwlmei
> > > 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the
> > > SAP_ME_MSG_CHECK_SHARED_AREA message -19
> > >
> > > And when the host comes back up, there is no connectivity. Restarting
> > > iwd fixes the problem.
> >
> > I am hitting the same problem, on Kernel 5.19, with the same Intel Wi- Fi 6
> > AX201 device. I reported this initially to iwd, but they said it might be a kernel
> > problem, so I found this thread.
> >
> > Has there been a solution since?
> >
>
> I moved on to other things, so I'll let the other people reply on this.
>
> Cheers

@Joachim - Can you please file a bug in kernel bugzilla, we will assign
someone internally to look into this.

Thanks,
Gregory

2022-08-14 16:59:36

by Joachim Breitner

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

Hi,

Am Sonntag, dem 14.08.2022 um 14:06 +0000 schrieb Greenman, Gregory:
> @Joachim - Can you please file a bug in kernel bugzilla, we will assign
> someone internally to look into this.

Sure thing! https://bugzilla.kernel.org/show_bug.cgi?id=216362

Let me know if I can be of any help, giving more information or testing
patches.

Cheers,
Joachim
--
Joachim Breitner
[email protected]
http://www.joachim-breitner.de/

2022-08-15 11:06:28

by Toke Høiland-Jørgensen

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

Joachim Breitner <[email protected]> writes:

> Hi,
>
> Am Sonntag, dem 14.08.2022 um 14:06 +0000 schrieb Greenman, Gregory:
>> @Joachim - Can you please file a bug in kernel bugzilla, we will assign
>> someone internally to look into this.
>
> Sure thing! https://bugzilla.kernel.org/show_bug.cgi?id=216362

I already had a bz open: https://bugzilla.kernel.org/show_bug.cgi?id=215937

> Let me know if I can be of any help, giving more information or testing
> patches.

Last I heard back from Avraham he indicated that this was due to a bug
in the MEI bus driver, and asked me to reproduce with debug prints
enabled in that, which I have unfortunately not had the time to do :(

-Toke

2022-08-18 07:43:36

by Stern, Avraham

[permalink] [raw]
Subject: RE: iwlwifi devices disappear after suspend on kernel 5.17

Hi Joachim,

Do you see the iwd task hang stack trace like in Toke's logs?
Can you please send full dmesg?

Thanks,
Avi.

-----Original Message-----
From: Joachim Breitner <[email protected]>
Sent: Friday, August 12, 2022 19:20
To: [email protected]
Cc: Stern, Avraham <[email protected]>; Grumbach, Emmanuel <[email protected]>; Greenman, Gregory <[email protected]>; [email protected]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

Hello Emmanuel,

> suspend. I get errors like these in dmesg:
>
> [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by
> local choice (Reason: 3=DEAUTH_LEAVING) [104393.347775] iwlmei
> 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK
> from CSME on HOST_GOES_DOWN message [104393.347876] iwlmei
> 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the
> SAP_ME_MSG_CHECK_SHARED_AREA message -19
>
> And when the host comes back up, there is no connectivity. Restarting
> iwd fixes the problem.

I am hitting the same problem, on Kernel 5.19, with the same Intel Wi- Fi 6 AX201 device. I reported this initially to iwd, but they said it might be a kernel problem, so I found this thread.

Has there been a solution since?

Thanks,
Joachim


--
Joachim Breitner
[email protected]
http://www.joachim-breitner.de/

---------------------------------------------------------------------
A member of the Intel Corporation group of companies

This e-mail and any attachments may contain confidential material for
the sole use of the intended recipient(s). Any review or distribution
by others is strictly prohibited. If you are not the intended
recipient, please contact the sender and delete all copies.

2022-08-18 07:58:16

by Grumbach, Emmanuel

[permalink] [raw]
Subject: RE: iwlwifi devices disappear after suspend on kernel 5.17

>
> Hi Joachim,
>
> Do you see the iwd task hang stack trace like in Toke's logs?
> Can you please send full dmesg?
>
> Thanks,
> Avi.

FWIW: there is this fix for iwlmei that didn't make it upstream yet:
07f408bb49cc31dccc45c5150e6fbc26a608db10 in our internal tree.
This was meant to avoid the error messages that Toke reported, but
didn't fix the interface remaining down.


>
> -----Original Message-----
> From: Joachim Breitner <[email protected]>
> Sent: Friday, August 12, 2022 19:20
> To: [email protected]
> Cc: Stern, Avraham <[email protected]>; Grumbach, Emmanuel
> <[email protected]>; Greenman, Gregory
> <[email protected]>; [email protected]
> Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17
>
> Hello Emmanuel,
>
> > suspend. I get errors like these in dmesg:
> >
> > [104393.142264] wlan0: deauthenticating from 4c:60:de:ea:b8:58 by
> > local choice (Reason: 3=DEAUTH_LEAVING) [104393.347775] iwlmei
> > 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: Couldn't get ACK
> > from CSME on HOST_GOES_DOWN message [104393.347876] iwlmei
> > 0000:00:16.0-13280904-7792-4fcb-a1aa-5e70cbb1e865: failed to send the
> > SAP_ME_MSG_CHECK_SHARED_AREA message -19
> >
> > And when the host comes back up, there is no connectivity. Restarting
> > iwd fixes the problem.
>
> I am hitting the same problem, on Kernel 5.19, with the same Intel Wi- Fi 6
> AX201 device. I reported this initially to iwd, but they said it might be a kernel
> problem, so I found this thread.
>
> Has there been a solution since?
>
> Thanks,
> Joachim
>
>
> --
> Joachim Breitner
> [email protected]
> http://www.joachim-breitner.de/

2022-08-18 08:15:12

by Joachim Breitner

[permalink] [raw]
Subject: Re: iwlwifi devices disappear after suspend on kernel 5.17

Hi Avi,

Am Donnerstag, dem 18.08.2022 um 07:41 +0000 schrieb Stern, Avraham:
> Do you see the iwd task hang stack trace like in Toke's logs?
> Can you please send full dmesg?

not sure how to spot the task hang. But here is my full dmesg of a
boot, suspend, wakeup cycle.

Cheers,
Joachim
--
Joachim Breitner
[email protected]
http://www.joachim-breitner.de/


Attachments:
dmesg.txt (79.06 kB)