Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

aarch64: aws: some instance types fail in UEFI #920

Closed
dustymabe opened this issue Aug 10, 2021 · 34 comments
Closed

aarch64: aws: some instance types fail in UEFI #920

dustymabe opened this issue Aug 10, 2021 · 34 comments
Labels
jira for syncing to jira kind/bug

Comments

@dustymabe
Copy link
Member

For some reason some instance types fail to launch.. a1.metal seems fine. c6g.xlarge and a1.2xlarge fail with:

UEFI firmware (version  built at 09:00:00 on Nov  1 2018)

[2J[01;01H[=3h[2J[01;01H[2J[01;01H[=3h[2J[01;01H[2J[01;01H[=3h[2J[01;01H[0m[35m[40m[0m[37m[40merror: ../../grub-core/term/serial.c:217:serial port `com0' isn't found.

error: ../../grub-core/commands/terminal.c:138:terminal `serial' isn't found.

error: ../../grub-core/commands/terminal.c:138:terminal `serial' isn't found.

[0m[30m[40m[2J[01;01H[0m[37m[40m[20;07HUse the ^ and v keys to change the selection.                       

      Press 'e' to edit the selected item, or 'c' for a command prompt.   

      Press Escape to return to the previous menu.                        [04;80H [0m[30m[47m[04;01H      Fedora CoreOS 34.20210809.dev.3 (ostree:0)                               [0m[37m[40m[04;79H[05;01H                                                                               [05;79H[06;01H                                                                               [06;79H[07;01H                                                                               [07;79H[08;01H                                                                               [08;79H[09;01H                                                                               [09;79H[10;01H                                                                               [10;79H[11;01H                                                                               [11;79H[12;01H                                                                               [12;79H[13;01H                    [2021-08-10T15:55:18.860313]                                                           [13;79H[14;01H                                                                               [14;79H[15;01H                                                                               [15;79H[16;01H                                                                               [16;79H[17;01H                                                                               [17;79H[18;01H                                                                               [18;79H[18;80H [04;79H[23;01H   The selected entry will be started automatically in 1s.                     [04;79H[23;01H   The selected entry will be started automatically in 0s.                     [04;79H[0m[30m[40m[2J[01;01H[0m[37m[40m[0m[30m[40m[2J[01;01H[0m[37m[40mEFI stub: Booting Linux Kernel...

EFI stub: Using DTB from configuration table

EFI stub: Exiting boot services and installing virtual address map...
@miabbott
Copy link
Member

FWIW, I was able to successfully launch an instance of Fedora Cloud 34 on c6g.xlarge

@dustymabe dustymabe added the jira for syncing to jira label Aug 11, 2021
@ausil
Copy link

ausil commented Aug 18, 2021

For RHCOS instances we have been using m6g instances and they have been fine

@dustymabe
Copy link
Member Author

Just booted RHCOS on m6g.xlarge. From the serial console log I see something very similar.. except the system comes all the way up.. So the error messages here are mostly no info for us to go on.

UEFI firmware (version  built at 09:00:00 on Nov  1 2018)

�[2J�[01;01H�[=3h�[2J�[01;01H�[2J�[01;01H�[=3h�[2J�[01;01H�[2J�[01;01H�[=3h�[2J�[01;01H�[0m�[35m�[40m�[0m�[37m�[40mSystem BootOrder not found.  Initializing defaults.

Creating boot entry "Boot0003" with label "Red Hat Enterprise Linux" for file "\EFI\redhat\shimaa64.efi"



error: ../../grub-core/term/serial.c:219:serial port `com0' isn't found.

error: ../../grub-core/commands/terminal.c:140:terminal `serial' isn't found.

error: ../../grub-core/commands/terminal.c:140:terminal `serial' isn't found.

�[0m�[30m�[40m�[2J�[01;01H�[0m�[37m�[40m�[20;07HUse the ^ and v keys to change the selection.                       

      Press 'e' to edit the selected item, or 'c' for a command prompt.   

      Press Escape to return to the previous menu.                        �[04;80H �[0m�[30m�[47m�[04;01H      Red Hat Enterprise Linux CoreOS 49.84.202106302247-0 (Ootpa) (ostree:0)  �[0m�[37m�[40m�[04;79H�[05;01H                                                                               �[05;79H�[06;01H                                                                               �[06;79H�[07;01H                                                                               �[07;79H�[08;01H                                                                               �[08;79H�[09;01H                                                                               �[09;79H�[10;01H                                                                               �[10;79H�[11;01H                                                                               �[11;79H�[12;01H                                                                               �[12;79H�[13;01H                    [2021-08-18T15:41:09.141048]                                                           �[13;79H�[14;01H                                                                               �[14;79H�[15;01H                                                                               �[15;79H�[16;01H                                                                               �[16;79H�[17;01H                                                                               �[17;79H�[18;01H                                                                               �[18;79H�[18;80H �[04;79H�[23;01H   The selected entry will be started automatically in 1s.                     �[04;79H�[23;01H   The selected entry will be started automatically in 0s.                     �[04;79H�[0m�[30m�[40m�[2J�[01;01H�[0m�[37m�[40m�[0m�[30m�[40m�[2J�[01;01H�[0m�[37m�[40mEFI stub: Booting Linux Kernel...

EFI stub: Using DTB from configuration table

EFI stub: Exiting boot services and installing virtual address map...

What is useful to know from this datapoint is that the FCOS and RHCOS grub configuration files are the exact same so at least we should be able to rule out some grub.cfg misconfiguration.

@dustymabe
Copy link
Member Author

ok rebasing from RHCOS to FCOS seems to work so the Fedora kernel itself should be fine?

@ausil
Copy link

ausil commented Aug 18, 2021

ok rebasing from RHCOS to FCOS seems to work so the Fedora kernel itself should be fine?

That likely indicates that fcos is not setting up /boot correctly

@dustymabe
Copy link
Member Author

dustymabe commented Aug 18, 2021

After some investigation I think what I'm seeing is that if I add console=ttyS0 to the kernel command line then the instance(s) are able to boot. If I don't have any console= entries then the machine never comes up (and obviously I can't see any data on the serial console). Also for some reason the "screenshot" feature never works on these instance types so I can never really see what is on the VGA console.

@dustymabe
Copy link
Member Author

Without further investigation (pending me working on it today) here are some thoughts:

@dustymabe
Copy link
Member Author

Summary:

  • RHCOS, NO console=ttyS0 -> boots fine
  • FCOS, NO console=ttyS0 -> no boot
  • FCOS, with console=ttyS0 -> boots fine

@bgilbert
Copy link
Contributor

#110 is needed for #567 anyway.

@nullr0ute
Copy link

EFI stub: Using DTB from configuration table

Why are you using DTB? You should be using ACPI. I suspect this is the underlying problem. The serial console on an ACPI system is provided by the ACPI SPCR entry and on a SBSA compliant system is generally not ttyS0.

@dustymabe
Copy link
Member Author

dustymabe commented Aug 24, 2021

FTR I see the same (or similar) Using DTB output from Fedora Cloud Base 34 (ami-0947214b956697846 in us-east-1) on c6g.xlarge:

UEFI firmware (version  built at 09:00:00 on Nov  1 2018)
�[2J�[01;01H�[=3h�[2J�[01;01H�[2J�[01;01H�[=3h�[2J�[01;01H�[2J�[01;01H�[=3h�[2J�[01;01H�[0m�[35m�[40m�[0m�[37m�[40m�[0m�[30m�[40m�[2J�[01;01H�[0m�[37m�[40m�[20;07HUse the ^ and v keys to change the selection.                       
      Press 'e' to edit the selected item, or 'c' for a command prompt.   
      Press Escape to return to the previous menu.                        �[04;80H �[0m�[30m�[47m�[04;01H      Fedora (5.11.12-300.fc34.aarch64) 34 (Cloud Edition)                     �[0m�[37m�[40m�[04;79H�[05;01H      UEFI Firmware Settings                                                   �[05;79H�[06;01H                                                                               �[06;79H�[07;01H                                                                               �[07;79H�[08;01H                                                                               �[08;79H�[09;01H                                                                               �[09;79H�[10;01H                                                                               �[10;79H�[11;01H                                                                               �[11;79H�[12;01H                                                                               �[12;79H�[13;01H                    [2021-08-24T19:34:36.989715]                                                           �[13;79H�[14;01H                                                                               �[14;79H�[15;01H                                                                               �[15;79H�[16;01H                                                                               �[16;79H�[17;01H                                                                               �[17;79H�[18;01H                                                                               �[18;79H�[18;80H �[04;79H�[23;01H   The selected entry will be started automatically in 1s.                     �[04;79H�[23;01H   The selected entry will be started automatically in 0s.                     �[04;79H�[0m�[30m�[40m�[2J�[01;01H�[0m�[37m�[40m�[0m�[30m�[40m�[2J�[01;01H�[0m�[37m�[40mEFI stub: Booting Linux Kernel...
EFI stub: Using DTB from configuration table
EFI stub: Exiting boot services and installing virtual address map...
[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]
[    0.000000] Linux version 5.11.12-300.fc34.aarch64 (mockbuild@buildvm-a64-10.iad2.fedoraproject.org) (gcc (GCC) 11.0.1 20210324 (Red Hat 11.0.1-0), GNU ld version 2.35.1-41.fc34) #1 SMP Wed Apr 7 16:12:21 UTC 2021
[    0.000000] efi: EFI v2.70 by EDK II
[    0.000000] efi: SMBIOS=0x7bee0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7a69a018 RNG=0x7bfded98 MEMRESERVE=0x78546b18 
[    0.000000] efi: seeding entropy pool
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON)
[    0.000000] ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001      01000013)
[    0.000000] ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001)
[    0.000000] ACPI: DSDT 0x0000000078640000 00154F (v03 AMAZON AMZNDSDT 00000001 INTL 20160527)
[    0.000000] ACPI: APIC 0x00000000786C0000 0001A8 (v03 AMAZON AMZNAPIC 00000001 AMZN 00000001)
[    0.000000] ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001)
[    0.000000] ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001)
[    0.000000] ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001)
[    0.000000] ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001)
[    0.000000] ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001)
[    0.000000] ACPI: PPTT 0x0000000078650000 00010C (v02 AMAZON AMZNPPTT 00000001 AMZN 00000001)
[    0.000000] ACPI: SPCR: console: uart,mmio,0x90a0000,115200
[    0.000000] ACPI: NUMA: Failed to initialise from firmware
[    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000005b6ffffff]
[    0.000000] NUMA: NODE_DATA [mem 0x5b5fee8c0-0x5b6004fff]

@nullr0ute
Copy link

[ 0.000000] ACPI: SPCR: console: uart,mmio,0x90a0000,115200

FYI This line is the console provided by the ACPI tables.

@dustymabe
Copy link
Member Author

FYI This line is the console provided by the ACPI tables.

Does that mean it is using ACPI even though EFI stub: Using DTB is in the output?

I'm trying to understand where we're going wrong here. If I try to force acpi on FCOS with acpi=force I get this:

UEFI firmware (version  built at 09:00:00 on Nov  1 2018)

�[2J�[01;01H�[=3h�[2J�[01;01H�[2J�[01;01H�[=3h�[2J�[01;01H�[2J�[01;01H�[=3h�[2J�[01;01H�[0m�[35m�[40m�[0m�[37m�[40m�[0m�[30m�[40m�[2J�[01;01H�[0m�[37m�[40m�[20;07HUse the ^ and v keys to change the selection.                       

      Press 'e' to edit the selected item, or 'c' for a command prompt.   

      Press Escape to return to the previous menu.                        �[04;80H �[0m�[30m�[47m�[04;01H      Fedora CoreOS 34.20210824.dev.0 (ostree:0)                               �[0m�[37m�[40m�[04;79H�[05;01H                                                                               �[05;79H�[06;01H                                                                               �[06;79H�[07;01H                                                                               �[07;79H�[08;01H                                                                               �[08;79H�[09;01H                                                                               �[09;79H�[10;01H                                                                               �[10;79H�[11;01H                                                                               �[11;79H�[12;01H                                                                               �[12;79H�[13;01H                    [2021-08-24T21:16:59.439744]                                                           �[13;79H�[14;01H                                                                               �[14;79H�[15;01H                                                                               �[15;79H�[16;01H                                                                               �[16;79H�[17;01H                                                                               �[17;79H�[18;01H                                                                               �[18;79H�[18;80H �[04;79H�[23;01H   The selected entry will be started automatically in 1s.                     �[04;79H�[23;01H   The selected entry will be started automatically in 0s.                     �[04;79H�[0m�[30m�[40m�[2J�[01;01H�[0m�[37m�[40m�[0m�[30m�[40m�[2J�[01;01H�[0m�[37m�[40mEFI stub: Booting Linux Kernel...

EFI stub: ERROR: FIRMWARE BUG: kernel image not aligned on 64k boundary
EFI stub: Using DTB from configuration table
EFI stub: Exiting boot services and installing virtual address map...

@nullr0ute
Copy link

TBH it's hard to tell from the information provided. Would likely need to login to a system to see

@jcajka
Copy link
Contributor

jcajka commented Sep 3, 2021

On first glance, if I'm not mistaken, issue is only showing up on the vitalizedvirtualized/hypervized aws aarch64 instances(non-metal ones), but not always. Baremetal ones appears fine. Does that sound right?

@dustymabe
Copy link
Member Author

On first glance, if I'm not mistaken, issue is only showing up on the vitalizedvirtualized/hypervized aws aarch64 instances(non-metal ones)

Correct

but not always.

on the instance types it doesn't boot on, it never boots. It's consistent.

Baremetal ones appears fine. Does that sound right?

Sounds right, though I've only tested on a1.metal.

dustymabe added a commit to dustymabe/fedora-coreos-pipeline that referenced this issue Sep 5, 2021
This is a workaround to get console=ttyS0,115200n8 into the
aarch64 AWS image. It does so by applying the following patch
to gf-platformid:

```diff
diff --git a/usr/lib/coreos-assembler/gf-platformid b/usr/lib/coreos-assembler/gf-platformid
index 2912b322c..36d089651 100755
--- a/usr/lib/coreos-assembler/gf-platformid
+++ b/usr/lib/coreos-assembler/gf-platformid
@@ -46,7 +46,11 @@ blscfg_path=$(coreos_gf glob-expand /boot/loader/entries/ostree-*.conf)
 coreos_gf download "${blscfg_path}" "${tmpd}"/bls.conf
 # Remove any platformid currently there
 sed -i -e 's, ignition.platform.id=[a-zA-Z0-9]*,,g' "${tmpd}"/bls.conf
-sed -i -e 's,^\(options .*\),\1 ignition.platform.id='"${platformid}"',' "${tmpd}"/bls.conf
+if [ "${platformid}" == 'aws' ]; then
+    sed -i -e 's|^\(options .*\)|\1 ignition.platform.id='"${platformid}"' console=ttyS0,115200n8|' "${tmpd}"/bls.conf
+else
+    sed -i -e 's,^\(options .*\),\1 ignition.platform.id='"${platformid}"',' "${tmpd}"/bls.conf
+fi
 coreos_gf upload "${tmpd}"/bls.conf "${blscfg_path}"

 if [ "$basearch" = "s390x" ] ; then
```

Once coreos/fedora-coreos-config#1181 and
coreos/coreos-assembler#2400 land then we
won't need this any longer.

This implements a fix for coreos/fedora-coreos-tracker#920
dustymabe added a commit to dustymabe/fedora-coreos-pipeline that referenced this issue Sep 5, 2021
Now that we've hacked in console=ttyS0 (see
coreos/fedora-coreos-tracker#920
now we can start to upload/test there again.
dustymabe added a commit to dustymabe/coreos-assembler that referenced this issue Sep 6, 2021
Now that we've got console=ttyS0 in the aarch64 images they can boot
on all aarch64 instance types (see [1]). The c6g.xlarge is not a bare
metal instance type and thus will boot much faster so let's go with
that.

[1] coreos/fedora-coreos-tracker#920)
dustymabe added a commit to dustymabe/fedora-coreos-pipeline that referenced this issue Sep 6, 2021
This is a workaround to get console=ttyS0,115200n8 into the
aarch64 AWS image. It does so by applying the following patch
to gf-platformid:

```diff
diff --git a/usr/lib/coreos-assembler/gf-platformid b/usr/lib/coreos-assembler/gf-platformid
index 2912b322c..36d089651 100755
--- a/usr/lib/coreos-assembler/gf-platformid
+++ b/usr/lib/coreos-assembler/gf-platformid
@@ -46,7 +46,11 @@ blscfg_path=$(coreos_gf glob-expand /boot/loader/entries/ostree-*.conf)
 coreos_gf download "${blscfg_path}" "${tmpd}"/bls.conf
 # Remove any platformid currently there
 sed -i -e 's, ignition.platform.id=[a-zA-Z0-9]*,,g' "${tmpd}"/bls.conf
-sed -i -e 's,^\(options .*\),\1 ignition.platform.id='"${platformid}"',' "${tmpd}"/bls.conf
+if [ "${platformid}" == 'aws' ]; then
+    sed -i -e 's|^\(options .*\)|\1 ignition.platform.id='"${platformid}"' console=ttyS0,115200n8|' "${tmpd}"/bls.conf
+else
+    sed -i -e 's,^\(options .*\),\1 ignition.platform.id='"${platformid}"',' "${tmpd}"/bls.conf
+fi
 coreos_gf upload "${tmpd}"/bls.conf "${blscfg_path}"

 if [ "$basearch" = "s390x" ] ; then
```

Once coreos/fedora-coreos-config#1181 and
coreos/coreos-assembler#2400 land then we
won't need this any longer.

This implements a fix for coreos/fedora-coreos-tracker#920
dustymabe added a commit to dustymabe/fedora-coreos-pipeline that referenced this issue Sep 6, 2021
Now that we've hacked in console=ttyS0 (see
coreos/fedora-coreos-tracker#920
now we can start to upload/test there again.
dustymabe added a commit to coreos/coreos-assembler that referenced this issue Sep 6, 2021
Now that we've got console=ttyS0 in the aarch64 images they can boot
on all aarch64 instance types (see [1]). The c6g.xlarge is not a bare
metal instance type and thus will boot much faster so let's go with
that.

[1] coreos/fedora-coreos-tracker#920)
dustymabe added a commit to coreos/fedora-coreos-pipeline that referenced this issue Sep 6, 2021
This is a workaround to get console=ttyS0,115200n8 into the
aarch64 AWS image. It does so by applying the following patch
to gf-platformid:

```diff
diff --git a/usr/lib/coreos-assembler/gf-platformid b/usr/lib/coreos-assembler/gf-platformid
index 2912b322c..36d089651 100755
--- a/usr/lib/coreos-assembler/gf-platformid
+++ b/usr/lib/coreos-assembler/gf-platformid
@@ -46,7 +46,11 @@ blscfg_path=$(coreos_gf glob-expand /boot/loader/entries/ostree-*.conf)
 coreos_gf download "${blscfg_path}" "${tmpd}"/bls.conf
 # Remove any platformid currently there
 sed -i -e 's, ignition.platform.id=[a-zA-Z0-9]*,,g' "${tmpd}"/bls.conf
-sed -i -e 's,^\(options .*\),\1 ignition.platform.id='"${platformid}"',' "${tmpd}"/bls.conf
+if [ "${platformid}" == 'aws' ]; then
+    sed -i -e 's|^\(options .*\)|\1 ignition.platform.id='"${platformid}"' console=ttyS0,115200n8|' "${tmpd}"/bls.conf
+else
+    sed -i -e 's,^\(options .*\),\1 ignition.platform.id='"${platformid}"',' "${tmpd}"/bls.conf
+fi
 coreos_gf upload "${tmpd}"/bls.conf "${blscfg_path}"

 if [ "$basearch" = "s390x" ] ; then
```

Once coreos/fedora-coreos-config#1181 and
coreos/coreos-assembler#2400 land then we
won't need this any longer.

This implements a fix for coreos/fedora-coreos-tracker#920
dustymabe added a commit to coreos/fedora-coreos-pipeline that referenced this issue Sep 6, 2021
Now that we've hacked in console=ttyS0 (see
coreos/fedora-coreos-tracker#920
now we can start to upload/test there again.
@dustymabe
Copy link
Member Author

Still don't know the real root cause, but I applied a hack/fix in coreos/fedora-coreos-pipeline@ddd9da9 to get ttyS0 set as the console in AWS instance types.

The more appropriate long term fix will land in coreos/coreos-assembler#2400 and coreos/fedora-coreos-config#1181

@dustymabe dustymabe added the status/pending-stable-release Fixed upstream and in testing. Waiting on stable release. label Sep 7, 2021
@dustymabe
Copy link
Member Author

The fix for this went into stable stream release 34.20210821.3.0.

@pfliu
Copy link

pfliu commented Jul 14, 2022

It looks right in SPCR table.

iasl the SPCR table, and get the following

/*

  • Intel ACPI Component Architecture
  • AML/ASL+ Disassembler version 20220331 (64-bit version)
  • Copyright (c) 2000 - 2022 Intel Corporation
  • Disassembly of SPCR.data, Mon Jul 11 08:20:08 2022
  • ACPI Data Table [SPCR]
  • Format: [HexOffset DecimalOffset ByteLength] FieldName : FieldValue (in hex)
    */

[000h 0000 4] Signature : "SPCR" [Serial Port Console Redirection Table]
[004h 0004 4] Table Length : 00000050
[008h 0008 1] Revision : 02
[009h 0009 1] Checksum : B8
[00Ah 0010 6] Oem ID : "AMAZON"
[010h 0016 8] Oem Table ID : "AMZNSPCR"
[018h 0024 4] Oem Revision : 00000001
[01Ch 0028 4] Asl Compiler ID : "AMZN"
[020h 0032 4] Asl Compiler Revision : 00000001

[024h 0036 1] Interface Type : 00
[025h 0037 3] Reserved : 000000

[028h 0040 12] Serial Port Register : [Generic Address Structure]
[028h 0040 1] Space ID : 00 [SystemMemory]
[029h 0041 1] Bit Width : 08
[02Ah 0042 1] Bit Offset : 00
[02Bh 0043 1] Encoded Access Width : 01 [Byte Access:8]
[02Ch 0044 8] Address : 00000000090A0000

[034h 0052 1] Interrupt Type : 08
[035h 0053 1] PCAT-compatible IRQ : 00
[036h 0054 4] Interrupt : 00000025
[03Ah 0058 1] Baud Rate : 07
[03Bh 0059 1] Parity : 00
[03Ch 0060 1] Stop Bits : 01
[03Dh 0061 1] Flow Control : 02
[03Eh 0062 1] Terminal Type : 00
[04Ch 0076 1] Reserved : 00
[040h 0064 2] PCI Device ID : 8250
[042h 0066 2] PCI Vendor ID : 1D0F
[044h 0068 1] PCI Bus : 00
[045h 0069 1] PCI Device : 01
[046h 0070 1] PCI Function : 00
[047h 0071 4] PCI Flags : 00000000
[04Bh 0075 1] PCI Segment : 00
[04Ch 0076 4] Reserved : 00000000

Raw Table Data: Length 80 (0x50)

0000: 53 50 43 52 50 00 00 00 02 B8 41 4D 41 5A 4F 4E  // SPCRP.....AMAZON
0010: 41 4D 5A 4E 53 50 43 52 01 00 00 00 41 4D 5A 4E  // AMZNSPCR....AMZN
0020: 01 00 00 00 00 00 00 00 00 08 00 01 00 00 0A 09  // ................
0030: 00 00 00 00 08 00 25 00 00 00 07 00 01 02 00 00  // ......%.........
0040: 50 82 0F 1D 00 01 00 00 00 00 00 00 00 00 00 00  // P...............

@pfliu
Copy link

pfliu commented Jul 14, 2022

/sys/firmware/fdt has no extra info for device-tree, except the bootargs which is built from command line.

fdtdump /sys/firmware/fdt

**** fdtdump is a low-level debugging tool, not meant for general use.
**** If you want to decompile a dtb, you probably want
**** dtc -I dtb -O dts

/dts-v1/;
// magic: 0xd00dfeed
// totalsize: 0x3a1 (929)
// off_dt_struct: 0x38
// off_dt_strings: 0x2d8
// off_mem_rsvmap: 0x28
// version: 17
// last_comp_version: 17
// boot_cpuid_phys: 0x0
// size_dt_strings: 0xc9
// size_dt_struct: 0x2a0

/ {
#size-cells = <0x00000002>;
#address-cells = <0x00000002>;
chosen {
kaslr-seed = <0x00000000 0x00000000>;
linux,uefi-mmap-desc-ver = <0x00000001>;
linux,uefi-mmap-desc-size = <0x00000030>;
linux,uefi-mmap-size = <0x00000570>;
linux,uefi-mmap-start = <0x00000000 0x70018018>;
linux,uefi-system-table = <0x00000000 0x7bfd0018>;
bootargs = "BOOT_IMAGE=(hd0,gpt3)/ostree/fedora-coreos-2ba7ba90b211ccdb6fee9cd51ab52a98ac23ebb52f7161b4c90a9ad06cf2bd9c/vmlinuz-5.18.0-60.fc37.aarch64 mitigations=auto,nosmt ostree=/ostree/boot.1/fedora-coreos/2ba7ba90b211ccdb6fee9cd51ab52a98ac23ebb52f7161b4c90a9ad06cf2bd9c/0 ignition.platform.id=aws console=ttyS0,115200n8 root=UUID=98823168-e7ca-11ec-9d60-02b27aba87e2 rw rootflags=prjquota boot=UUID=96d6ad12-e7ca-11ec-8160-cf3db43af328 crashkernel=340M";
linux,initrd-end = <0x00000000 0x74d0c029>;
linux,initrd-start = <0x00000000 0x7001c000>;
};

@pfliu
Copy link

pfliu commented Jul 14, 2022

So I think even without specifying the console=ttyS0, the linux kernel should be able to use the default platform console as the code path in linux tree
acpi_parse_spcr()->add_preferred_console()->__add_preferred_console(), which finally sets the global variable 'preferred_console'

So it is a bug that the kernel can not detect the default platform console without specifying one explicitly.

@dustymabe
Copy link
Member Author

So it is a bug that the kernel can not detect the default platform console without specifying one explicitly.

thank @pfliu - Any idea how we can track down the commit that introduces that bug? Or maybe you can help us report it upstream?

Can you confirm you still see the same problem with latest Fedora kernels?

@pfliu
Copy link

pfliu commented Jul 20, 2022

Find some extra things if removing console=ttyS0.
The system boots up, despite that no message on console. So ssh login can get the dmesg info as the following: (as it shows, no console is registered, which is abnormal)

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]
[    0.000000] Linux version 5.18.0-60.fc37.aarch64 (root@ampere-mtjade-altra-03.khw4.lab.eng.bos.redhat.com) (gcc (GCC) 12.1.1 20220507 (Red Hat 12.1.1-1), GNU ld version 2.38-14.fc37) #1 SMP PREEMPT_DYNAMIC Tue Jun 14 00:12:44 EDT 2022
[    0.000000] efi: EFI v2.70 by EDK II
[    0.000000] efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7ac39a98 MOKvar=0x7be90000 RNG=0x7bfded18 MEMRESERVE=0x78573118 
[    0.000000] efi: seeding entropy pool
[    0.000000] random: crng init done
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON)
[    0.000000] ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001      01000013)
[    0.000000] ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001)
[    0.000000] ACPI: DSDT 0x0000000078640000 00154F (v03 AMAZON AMZNDSDT 00000001 INTL 20160527)
[    0.000000] ACPI: APIC 0x00000000786C0000 0001A8 (v03 AMAZON AMZNAPIC 00000001 AMZN 00000001)
[    0.000000] ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001)
[    0.000000] ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001)
[    0.000000] ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001)
[    0.000000] ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001)
[    0.000000] ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001)
[    0.000000] ACPI: PPTT 0x0000000078650000 00010C (v02 AMAZON AMZNPPTT 00000001 AMZN 00000001)
[    0.000000] ACPI: SPCR: console: uart,mmio,0x90a0000,115200
[    0.000000] NUMA: Failed to initialise from firmware
[    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000005b5ffffff]
[    0.000000] NUMA: NODE_DATA [mem 0x5b4ff86c0-0x5b500efff]
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]
[    0.000000]   DMA32    empty
[    0.000000]   Normal   [mem 0x0000000100000000-0x00000005b5ffffff]
[    0.000000]   Device   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000040000000-0x00000000786effff]
[    0.000000]   node   0: [mem 0x00000000786f0000-0x000000007872ffff]
[    0.000000]   node   0: [mem 0x0000000078730000-0x000000007bbfffff]
[    0.000000]   node   0: [mem 0x000000007bc00000-0x000000007bfdffff]
[    0.000000]   node   0: [mem 0x000000007bfe0000-0x000000007fffffff]
[    0.000000]   node   0: [mem 0x0000000400000000-0x00000005b5ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000005b5ffffff]
[    0.000000] On node 0, zone Normal: 8192 pages in unavailable ranges
[    0.000000] cma: Reserved 64 MiB at 0x000000007c000000
[    0.000000] crashkernel reserved: 0x000000005aa00000 - 0x000000006fe00000 (340 MB)
[    0.000000] psci: probing for conduit method from ACPI.
[    0.000000] psci: PSCIv1.0 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: Trusted OS migration not required
[    0.000000] psci: SMC Calling Convention v1.1
[    0.000000] percpu: Embedded 31 pages/cpu s88744 r8192 d30040 u126976
[    0.000000] pcpu-alloc: s88744 r8192 d30040 u126976 alloc=31*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] CPU features: detected: GIC system register CPU interface
[    0.000000] CPU features: detected: Hardware dirty bit management
[    0.000000] CPU features: detected: Spectre-v4
[    0.000000] CPU features: detected: Spectre-BHB
[    0.000000] CPU features: kernel page table isolation forced ON by KASLR
[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
[    0.000000] CPU features: detected: ARM erratum 1418040
[    0.000000] CPU features: detected: ARM erratum 1542419 (kernel portion)
[    0.000000] Fallback order for Node 0: 0 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2024064
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: BOOT_IMAGE=(hd0,gpt3)/ostree/fedora-coreos-2ba7ba90b211ccdb6fee9cd51ab52a98ac23ebb52f7161b4c90a9ad06cf2bd9c/vmlinuz-5.18.0-60.fc37.aarch64 mitigations=auto,nosmt ostree=/ostree/boot.0/fedora-coreos/2ba7ba90b211ccdb6fee9cd51ab52a98ac23ebb52f7161b4c90a9ad06cf2bd9c/0 ignition.platform.id=aws root=UUID=98823168-e7ca-11ec-9d60-02b27aba87e2 rw rootflags=prjquota boot=UUID=96d6ad12-e7ca-11ec-8160-cf3db43af328 crashkernel=340M
[    0.000000] Unknown kernel command line parameters "BOOT_IMAGE=(hd0,gpt3)/ostree/fedora-coreos-2ba7ba90b211ccdb6fee9cd51ab52a98ac23ebb52f7161b4c90a9ad06cf2bd9c/vmlinuz-5.18.0-60.fc37.aarch64 ostree=/ostree/boot.0/fedora-coreos/2ba7ba90b211ccdb6fee9cd51ab52a98ac23ebb52f7161b4c90a9ad06cf2bd9c/0 boot=UUID=96d6ad12-e7ca-11ec-8160-cf3db43af328", will be passed to user space.
[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x0000000056a00000-0x000000005aa00000] (64MB)
[    0.000000] Memory: 7448344K/8224768K available (16384K kernel code, 4348K rwdata, 13156K rodata, 7360K init, 10809K bss, 710888K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 55894 entries in 219 pages
[    0.000000] ftrace: allocated 219 pages with 6 groups
[    0.000000] trace event string verifier disabled
[    0.000000] Dynamic Preempt: voluntary
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=4096 to nr_cpu_ids=4.
[    0.000000] 	Trampoline variant of Tasks RCU enabled.
[    0.000000] 	Rude variant of Tasks RCU enabled.
[    0.000000] 	Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GICv3: 96 SPIs implemented
[    0.000000] GICv3: 0 Extended SPIs implemented
[    0.000000] GICv3: Distributor has no Range Selector support
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GICv3: 16 PPIs implemented
[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000
[    0.000000] ITS [mem 0x10080000-0x1009ffff]
[    0.000000] ITS@0x0000000010080000: allocated 8192 Devices @4001e0000 (indirect, esz 8, psz 64K, shr 1)
[    0.000000] ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4001f0000 (flat, esz 8, psz 64K, shr 1)
[    0.000000] GICv3: using LPI property table @0x0000000400200000
[    0.000000] ITS: Using hypervisor restricted LPI range [128]
[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000400210000
[    0.000000] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
[    0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040
[    0.000000] arch_timer: cp15 timer(s) running at 121.87MHz (virt).
[    0.000000] clocksource: arch_sys_counter: mask: 0x3ffffffffffffff max_cycles: 0x383759f8ff, max_idle_ns: 881590415659 ns
[    0.000000] sched_clock: 58 bits at 121MHz, resolution 8ns, wraps every 4398046511103ns
[    0.000036] Console: colour dummy device 80x25
[    0.000063] ACPI: Core revision 20211217
[    0.000105] Calibrating delay loop (skipped), value calculated using timer frequency.. 243.75 BogoMIPS (lpj=1218750)
[    0.000108] pid_max: default: 32768 minimum: 301
[    0.000139] LSM: Security Framework initializing
[    0.000152] Yama: becoming mindful.
[    0.000158] SELinux:  Initializing.
[    0.000189] LSM support for eBPF active
[    0.000191] landlock: Up and running.
[    0.000214] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.000220] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.000646] cblist_init_generic: Setting adjustable number of callback queues.
[    0.000650] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.000668] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.000685] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.000731] rcu: Hierarchical SRCU implementation.
[    0.000969] Platform MSI: ITS@0x10080000 domain created
[    0.000975] PCI/MSI: ITS@0x10080000 domain created
[    0.000978] fsl-mc MSI: ITS@0x10080000 domain created
[    0.000985] Remapping and enabling EFI services.
[    0.001187] smp: Bringing up secondary CPUs ...
[    0.001376] Detected PIPT I-cache on CPU1
[    0.001394] GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000
[    0.001436] GICv3: CPU1: using allocated LPI pending table @0x0000000400220000
[    0.001452] arch_timer: Enabling local workaround for ARM erratum 1418040
[    0.001491] CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1]
[    0.001737] Detected PIPT I-cache on CPU2
[    0.001754] GICv3: CPU2: found redistributor 2 region 0:0x0000000010240000
[    0.001788] GICv3: CPU2: using allocated LPI pending table @0x0000000400230000
[    0.001804] arch_timer: Enabling local workaround for ARM erratum 1418040
[    0.001825] CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1]
[    0.002043] Detected PIPT I-cache on CPU3
[    0.002061] GICv3: CPU3: found redistributor 3 region 0:0x0000000010260000
[    0.002094] GICv3: CPU3: using allocated LPI pending table @0x0000000400240000
[    0.002111] arch_timer: Enabling local workaround for ARM erratum 1418040
[    0.002131] CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1]
[    0.002183] smp: Brought up 1 node, 4 CPUs
[    0.002193] SMP: Total of 4 processors activated.
[    0.002195] CPU features: detected: 32-bit EL0 Support
[    0.002197] CPU features: detected: Instruction cache invalidation not required for I/D coherence
[    0.002198] CPU features: detected: Data cache clean to the PoU not required for I/D coherence
[    0.002200] CPU features: detected: Common not Private translations
[    0.002201] CPU features: detected: CRC32 instructions
[    0.002202] CPU features: detected: Data cache clean to Point of Persistence
[    0.002204] CPU features: detected: RCpc load-acquire (LDAPR)
[    0.002205] CPU features: detected: LSE atomic instructions
[    0.002206] CPU features: detected: Privileged Access Never
[    0.002207] CPU features: detected: RAS Extension Support
[    0.002209] CPU features: detected: Speculative Store Bypassing Safe (SSBS)
[    0.014804] CPU: All CPU(s) started at EL1
[    0.014822] alternatives: patching kernel code
[    0.015349] devtmpfs: initialized
[    0.016130] Registered cp15_barrier emulation handler
[    0.016138] Registered setend emulation handler
[    0.016141] KASLR enabled
[    0.016182] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.016187] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.016444] pinctrl core: initialized pinctrl subsystem
[    0.016595] SMBIOS 3.0.0 present.
[    0.016598] DMI: Amazon EC2 c6g.xlarge/, BIOS 1.0 11/1/2018
[    0.016716] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.017454] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.017510] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.017591] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.017608] audit: initializing netlink subsys (disabled)
[    0.017656] audit: type=2000 audit(0.010:1): state=initialized audit_enabled=0 res=1
[    0.017771] thermal_sys: Registered thermal governor 'fair_share'
[    0.017773] thermal_sys: Registered thermal governor 'step_wise'
[    0.017775] thermal_sys: Registered thermal governor 'user_space'
[    0.017783] cpuidle: using governor menu
[    0.017825] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.017873] ASID allocator initialised with 32768 entries
[    0.017909] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.017954] Serial: AMBA PL011 UART driver
[    0.019581] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.019583] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
[    0.019585] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.019586] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
[    0.019786] cryptd: max_cpu_qlen set to 1000
[    0.019998] raid6: skipped pq benchmark and selected neonx8
[    0.020011] raid6: using neon recovery algorithm
[    0.020068] ACPI: Added _OSI(Module Device)
[    0.020070] ACPI: Added _OSI(Processor Device)
[    0.020071] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.020073] ACPI: Added _OSI(Processor Aggregator Device)
[    0.020075] ACPI: Added _OSI(Linux-Dell-Video)
[    0.020076] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    0.020077] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    0.020791] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.021125] ACPI: Interpreter enabled
[    0.021126] ACPI: Using GIC for interrupt routing
[    0.021135] ACPI: MCFG table detected, 1 entries
[    0.023709] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f])
[    0.023721] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI EDR HPX-Type3]
[    0.023772] acpi PNP0A08:00: _OSC: platform does not support [LTR DPC]
[    0.023846] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability]
[    0.023976] acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00
[    0.023982] acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f]
[    0.024000] ACPI: Remapped I/O 0x000000001fff0000 to [io  0x0000-0xffff window]
[    0.024298] acpiphp: Slot [1] registered
[    0.024314] acpiphp: Slot [2] registered
[    0.024327] acpiphp: Slot [3] registered
[    0.024341] acpiphp: Slot [4] registered
[    0.024356] acpiphp: Slot [5] registered
[    0.024370] acpiphp: Slot [6] registered
[    0.024383] acpiphp: Slot [7] registered
[    0.024396] acpiphp: Slot [8] registered
[    0.024411] acpiphp: Slot [9] registered
[    0.024425] acpiphp: Slot [10] registered
[    0.024439] acpiphp: Slot [11] registered
[    0.024452] acpiphp: Slot [12] registered
[    0.024466] acpiphp: Slot [13] registered
[    0.024479] acpiphp: Slot [14] registered
[    0.024492] acpiphp: Slot [15] registered
[    0.024505] acpiphp: Slot [16] registered
[    0.024518] acpiphp: Slot [17] registered
[    0.024532] acpiphp: Slot [18] registered
[    0.024546] acpiphp: Slot [19] registered
[    0.024560] acpiphp: Slot [20] registered
[    0.024573] acpiphp: Slot [21] registered
[    0.024587] acpiphp: Slot [22] registered
[    0.024600] acpiphp: Slot [23] registered
[    0.024614] acpiphp: Slot [24] registered
[    0.024628] acpiphp: Slot [25] registered
[    0.024641] acpiphp: Slot [26] registered
[    0.024654] acpiphp: Slot [27] registered
[    0.024667] acpiphp: Slot [28] registered
[    0.024680] acpiphp: Slot [29] registered
[    0.024695] acpiphp: Slot [30] registered
[    0.024709] acpiphp: Slot [31] registered
[    0.024731] PCI host bridge to bus 0000:00
[    0.024733] pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window]
[    0.024735] pci_bus 0000:00: root bus resource [io  0x0000-0xffff window]
[    0.024737] pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window]
[    0.024739] pci_bus 0000:00: root bus resource [bus 00-0f]
[    0.024778] pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000
[    0.025033] pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003
[    0.025083] pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff]
[    0.025310] pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802
[    0.025802] pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff]
[    0.027712] pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.027896] pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000
[    0.027942] pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff]
[    0.027970] pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref]
[    0.027997] pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff]
[    0.028178] pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.028362] pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref]
[    0.028370] pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff]
[    0.028378] pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff]
[    0.028564] pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff]
[    0.028573] pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff]
[    0.028582] pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window]
[    0.028584] pci_bus 0000:00: resource 5 [io  0x0000-0xffff window]
[    0.028586] pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window]
[    0.028620] ACPI: PCI: Interrupt link GSI0 configured for IRQ 35
[    0.028628] ACPI: PCI: Interrupt link GSI1 configured for IRQ 36
[    0.028636] ACPI: PCI: Interrupt link GSI2 configured for IRQ 37
[    0.028643] ACPI: PCI: Interrupt link GSI3 configured for IRQ 38
[    0.029097] iommu: Default domain type: Translated 
[    0.029100] iommu: DMA domain TLB invalidation policy: lazy mode 
[    0.029201] SCSI subsystem initialized
[    0.029239] libata version 3.00 loaded.
[    0.029257] ACPI: bus type USB registered
[    0.029276] usbcore: registered new interface driver usbfs
[    0.029282] usbcore: registered new interface driver hub
[    0.029288] usbcore: registered new device driver usb
[    0.029320] pps_core: LinuxPPS API ver. 1 registered
[    0.029321] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.029324] PTP clock support registered
[    0.029377] EDAC MC: Ver: 3.0.0
[    0.029534] Registered efivars operations
[    0.029797] NetLabel: Initializing
[    0.029799] NetLabel:  domain hash size = 128
[    0.029800] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.029813] NetLabel:  unlabeled traffic allowed by default
[    0.029817] mctp: management component transport protocol core
[    0.029818] NET: Registered PF_MCTP protocol family
[    0.029873] vgaarb: loaded
[    0.029981] clocksource: Switched to clocksource arch_sys_counter
[    0.045755] VFS: Disk quotas dquot_6.6.0
[    0.045769] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.045846] pnp: PnP ACPI init
[    0.045987] system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved
[    0.046001] pnp: PnP ACPI: found 1 devices
[    0.047730] NET: Registered PF_INET protocol family
[    0.047779] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.048790] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
[    0.048838] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.049000] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[    0.049321] TCP: Hash tables configured (established 65536 bind 65536)
[    0.049361] MPTCP token hash table entries: 8192 (order: 5, 196608 bytes, linear)
[    0.049389] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.049449] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.049550] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.049557] NET: Registered PF_XDP protocol family
[    0.049607] PCI: CLS 0 bytes, default 64
[    0.049622] ACPI: bus type thunderbolt registered
[    0.049734] Trying to unpack rootfs image as initramfs...
[    0.060291] hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 4 counters available
[    0.060308] kvm [1]: HYP mode not available
[    0.060612] Initialise system trusted keyrings
[    0.060626] Key type blacklist registered
[    0.060678] workingset: timestamp_bits=37 max_order=21 bucket_order=0
[    0.062112] zbud: loaded
[    0.062779] integrity: Platform Keyring initialized
[    0.062784] integrity: Machine keyring initialized
[    0.072608] NET: Registered PF_ALG protocol family
[    0.072612] xor: measuring software checksum speed
[    0.073208]    8regs           : 16560 MB/sec
[    0.073815]    32regs          : 16399 MB/sec
[    0.074333]    arm64_neon      : 19095 MB/sec
[    0.074334] xor: using function: arm64_neon (19095 MB/sec)
[    0.074338] Key type asymmetric registered
[    0.074339] Asymmetric key parser 'x509' registered
[    0.713585] Freeing initrd memory: 78784K
[    0.717874] alg: self-tests for CTR-KDF (hmac(sha256)) passed
[    0.717905] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)
[    0.717954] io scheduler mq-deadline registered
[    0.717958] io scheduler kyber registered
[    0.717998] io scheduler bfq registered
[    0.719498] atomic64_test: passed
[    0.720198] pl061_gpio ARMH0061:00: PL061 GPIO chip registered
[    0.722181] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
[    0.722202] ACPI: button: Power Button [PWRB]
[    0.722827] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[    0.724373] ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37
[    0.724404] serial 0000:00:01.0: enabling device (0010 -> 0012)
[    0.724774] 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A
[    0.724958] msm_serial: driver initialized
[    0.725130] ACPI: bus type drm_connector registered
[    0.725805] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.725809] ehci-pci: EHCI PCI platform driver
[    0.725840] usbcore: registered new interface driver usbserial_generic
[    0.725844] usbserial: USB Serial support registered for generic
[    0.725874] mousedev: PS/2 mouse device common for all mice
[    0.726025] rtc-efi rtc-efi.0: registered as rtc0
[    0.726045] rtc-efi rtc-efi.0: setting system clock to 2022-07-20T03:26:08 UTC (1658287568)
[    0.726167] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
[    0.726184] device-mapper: uevent: version 1.0.3
[    0.726241] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com
[    0.726428] ledtrig-cpu: registered to indicate activity on CPUs
[    0.726553] hid: raw HID events driver (C) Jiri Kosina
[    0.726583] usbcore: registered new interface driver usbhid
[    0.726584] usbhid: USB HID core driver
[    0.726760] drop_monitor: Initializing network drop monitor service
[    0.741304] Initializing XFRM netlink socket
[    0.741426] NET: Registered PF_INET6 protocol family
[    0.745799] Segment Routing with IPv6
[    0.745802] RPL Segment Routing with IPv6
[    0.745808] In-situ OAM (IOAM) with IPv6
[    0.745824] mip6: Mobile IPv6
[    0.745825] NET: Registered PF_PACKET protocol family
[    0.746078] registered taskstats version 1
[    0.746193] Loading compiled-in X.509 certificates
[    0.778536] Loaded X.509 cert 'Fedora kernel signing key: 1e7e36d12485697e2862f331e56f157ca71fd289'
[    0.778733] zswap: loaded using pool lzo/zbud
[    0.778804] page_owner is disabled
[    0.778859] Key type ._fscrypt registered
[    0.778861] Key type .fscrypt registered
[    0.778862] Key type fscrypt-provisioning registered
[    0.779178] Btrfs loaded, crc32c=crc32c-generic, zoned=yes, fsverity=yes
[    0.779195] Key type big_key registered
[    0.779325] Key type encrypted registered
[    0.779573] ima: secureboot mode disabled
[    0.779576] ima: No TPM chip found, activating TPM-bypass!
[    0.779586] Loading compiled-in module X.509 certificates
[    0.780232] Loaded X.509 cert 'Fedora kernel signing key: 1e7e36d12485697e2862f331e56f157ca71fd289'
[    0.780236] ima: Allocated hash algorithm: sha256
[    0.780246] ima: No architecture policies found
[    0.780261] evm: Initialising EVM extended attributes:
[    0.780262] evm: security.selinux
[    0.780263] evm: security.SMACK64 (disabled)
[    0.780264] evm: security.SMACK64EXEC (disabled)
[    0.780265] evm: security.SMACK64TRANSMUTE (disabled)
[    0.780266] evm: security.SMACK64MMAP (disabled)
[    0.780267] evm: security.apparmor (disabled)
[    0.780268] evm: security.ima
[    0.780269] evm: security.capability
[    0.780270] evm: HMAC attrs: 0x1
[    0.789358] alg: No test for 842 (842-scomp)
[    0.789399] alg: No test for 842 (842-generic)
[    0.919075] Warning: unable to open an initial console.
[    0.920790] Freeing unused kernel memory: 7360K
[    1.048012] Checked W+X mappings: passed, no W+X pages found
[    1.048019] rodata_test: all tests were successful
[    1.048038] Run /init as init process
[    1.048041]   with arguments:
[    1.048042]     /init
[    1.048043]   with environment:
[    1.048044]     HOME=/
[    1.048045]     TERM=linux
[    1.048046]     BOOT_IMAGE=(hd0,gpt3)/ostree/fedora-coreos-2ba7ba90b211ccdb6fee9cd51ab52a98ac23ebb52f7161b4c90a9ad06cf2bd9c/vmlinuz-5.18.0-60.fc37.aarch64
[    1.048047]     ostree=/ostree/boot.0/fedora-coreos/2ba7ba90b211ccdb6fee9cd51ab52a98ac23ebb52f7161b4c90a9ad06cf2bd9c/0
[    1.048048]     boot=UUID=96d6ad12-e7ca-11ec-8160-cf3db43af328
[    1.059393] systemd[1]: systemd v250.7-1.fc36 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.059472] systemd[1]: Detected virtualization amazon.
[    1.059477] systemd[1]: Detected architecture arm64.
[    1.059479] systemd[1]: Running in initial RAM disk.
[    1.059594] systemd[1]: No hostname configured, using default hostname.
[    1.059649] systemd[1]: Hostname set to <localhost>.
[    1.059734] systemd[1]: Initializing machine ID from VM UUID.
[    1.105817] systemd[1]: Failed to link BPF program. Assuming BPF is not available
[    1.196040] systemd[1]: Queued start job for default target initrd.target.
[    1.196325] systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
[    1.196413] systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
[    1.196446] systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
[    1.196462] systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
[    1.196481] systemd[1]: Reached target ignition-diskful-subsequent.target - Ignition Subsequent Boot Disk Setup.
[    1.196499] systemd[1]: Reached target ignition-subsequent.target - Subsequent (Not Ignition) boot complete.
[    1.196523] systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System.
[    1.196546] systemd[1]: Reached target paths.target - Path Units.
[    1.196570] systemd[1]: Reached target slices.target - Slice Units.
[    1.196589] systemd[1]: Reached target swap.target - Swaps.
[    1.196605] systemd[1]: Reached target timers.target - Timer Units.
[    1.196713] systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
[    1.196860] systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket.
[    1.196960] systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
[    1.197057] systemd[1]: Listening on systemd-journald.socket - Journal Socket.
[    1.197171] systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
[    1.197249] systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
[    1.197266] systemd[1]: Reached target sockets.target - Socket Units.
[    1.197292] systemd[1]: coreos-check-kernel.service - Check that initrd matches kernel was skipped because of a failed condition check (ConditionPathIsDirectory=!/usr/lib/modules/5.18.0-60.fc37.aarch64).
[    1.197337] systemd[1]: coreos-liveiso-persist-osmet.service - Persist osmet files (ISO) was skipped because of a failed condition check (ConditionKernelCommandLine=coreos.liveiso).
[    1.198484] systemd[1]: Starting coreos-touch-run-agetty.service - CoreOS: Touch /run/agetty.reload...
[    1.199366] systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
[    1.200811] systemd[1]: Starting systemd-journald.service - Journal Service...
[    1.201741] systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
[    1.202580] systemd[1]: Starting systemd-sysusers.service - Create System Users...
[    1.203424] systemd[1]: Starting systemd-vconsole-setup.service - Setup Virtual Console...
[    1.203976] systemd[1]: Finished coreos-touch-run-agetty.service - CoreOS: Touch /run/agetty.reload.
[    1.204296] systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
[    1.209794] systemd[1]: Finished systemd-vconsole-setup.service - Setup Virtual Console.
[    1.210886] systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
[    1.215880] systemd[1]: Finished systemd-sysusers.service - Create System Users.
[    1.215958] audit: type=1130 audit(1658287568.979:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.216870] systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
[    1.222690] systemd[1]: Started systemd-journald.service - Journal Service.
[    1.222801] audit: type=1130 audit(1658287568.989:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.223850] systemd-sysusers[292]: Creating group 'nobody' with GID 65534.
[    1.223997] systemd-sysusers[292]: Creating group 'users' with GID 100.
[    1.224102] systemd-sysusers[292]: Creating group 'root' with GID 999.
[    1.224197] systemd-sysusers[292]: Creating group 'dbus' with GID 998.
[    1.224258] audit: type=1130 audit(1658287568.989:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.224310] systemd-sysusers[292]: Creating user 'dbus' (System Message Bus) with UID 998 and GID 998.
[    1.224532] systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories...
[    1.224683] systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
[    1.232629] systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories.
[    1.232661] audit: type=1130 audit(1658287568.999:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.247484] fuse: init (API version 7.36)
[    1.247991] systemd-modules-load[291]: Inserted module 'fuse'
[    1.251750] IPMI message handler: version 39.2
[    1.253502] ipmi device interface
[    1.253629] systemd-modules-load[291]: Inserted module 'ipmi_devintf'
[    1.256911] systemd-modules-load[291]: Inserted module 'ip_tables'
[    1.260241] systemd[296]: dracut-cmdline-ask.service: Failed to set up standard input: No such device
[    1.260596] systemd[296]: dracut-cmdline-ask.service: Failed at step STDIN spawning /bin/dracut-cmdline-ask: No such device
[    1.261068] systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
[    1.261092] audit: type=1130 audit(1658287569.029:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.261410] systemd-modules-load[291]: Inserted module 'ip6_tables'
[    1.262205] systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook...
[    1.262543] systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
[    1.262598] audit: type=1130 audit(1658287569.029:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.263577] systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
[    1.270675] systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
[    1.270698] audit: type=1130 audit(1658287569.039:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.277065] dracut-cmdline[304]: dracut-36.20220618.3.1 (CoreOS) dracut-056-1.fc36
[    1.281039] dracut-cmdline[304]: Using kernel command line parameters:  rd.driver.pre=btrfs rd.driver.pre=dm_multipath   BOOT_IMAGE=(hd0,gpt3)/ostree/fedora-coreos-2ba7ba90b211ccdb6fee9cd51ab52a98ac23ebb52f7161b4c90a9ad06cf2bd9c/vmlinuz-5.18.0-60.fc37.aarch64 mitigations=auto,nosmt ostree=/ostree/boot.0/fedora-coreos/2ba7ba90b211ccdb6fee9cd51ab52a98ac23ebb52f7161b4c90a9ad06cf2bd9c/0 ignition.platform.id=aws root=UUID=98823168-e7ca-11ec-9d60-02b27aba87e2 rw rootflags=prjquota boot=UUID=96d6ad12-e7ca-11ec-8160-cf3db43af328 crashkernel=340M
[    1.398281] systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook.
[    1.398318] audit: type=1130 audit(1658287569.159:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.399427] systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook...
[    1.440497] systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook.
[    1.440539] audit: type=1130 audit(1658287569.209:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    1.442065] systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
[    1.461449] systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
[    1.462511] systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook...
[    1.478015] dracut-pre-trigger[445]: rd.md=0: removing MD RAID activation
[    1.511110] systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook.
[    1.512115] systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
[    1.586489] systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
[    1.587648] systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization...
[    1.623382] ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36
[    1.623433] ena 0000:00:05.0: enabling device (0010 -> 0012)
[    1.625042] ena 0000:00:05.0: ENA device version: 0.10
[    1.625047] ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1
[    1.644442] ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35
[    1.644534] nvme nvme0: pci function 0000:00:04.0
[    1.650770] nvme nvme0: 2/0/0 default/read/poll queues
[    1.654515]  nvme0n1: p1 p2 p3 p4
[    1.658398] udevadm[494]: systemd-udev-settle.service is deprecated. Please fix multipathd-configure.service not to pull it in.
[    1.680402] ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 02:b2:7a:ba:87:e2
[    1.681320] systemd-udevd[487]: Using default interface naming scheme 'v250'.
[    1.681937] ena 0000:00:05.0 ens5: renamed from eth0
[    1.794902] systemd[1]: Found device dev-disk-by\x2dlabel-root.device - Amazon Elastic Block Store root.
[    1.794978] systemd[1]: Found device dev-disk-by\x2duuid-98823168\x2de7ca\x2d11ec\x2d9d60\x2d02b27aba87e2.device - Amazon Elastic Block Store root.
[    1.795015] systemd[1]: Reached target initrd-root-device.target - Initrd Root Device.
[    1.795047] systemd[1]: ignition-ostree-mount-subsequent-sysroot.service - CoreOS: Mount (subsequent) /sysroot was skipped because of a failed condition check (ConditionKernelCommandLine=!root).
[    1.820955] systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization.
[    1.821160] systemd[1]: multipathd-configure.service - Device-Mapper Multipath Default Configuration was skipped because of a failed condition check (ConditionKernelCommandLine=rd.multipath=default).
[    1.822115] systemd[1]: Starting multipathd.service - Device-Mapper Multipath Device Controller...
[    1.836501] multipathd[510]: --------start up--------
[    1.836550] multipathd[510]: read /etc/multipath.conf
[    1.836580] multipathd[510]: /etc/multipath.conf does not exist, blacklisting all devices.
[    1.836595] multipathd[510]: You can run "/sbin/mpathconf --enable" to create
[    1.836610] multipathd[510]: /etc/multipath.conf. See man mpathconf(8) for more details
[    1.839002] multipathd[510]: path checkers start up
[    1.839835] multipathd[510]: /etc/multipath.conf does not exist, blacklisting all devices.
[    1.839857] multipathd[510]: You can run "/sbin/mpathconf --enable" to create
[    1.839872] multipathd[510]: /etc/multipath.conf. See man mpathconf(8) for more details
[    1.841319] systemd[1]: Started multipathd.service - Device-Mapper Multipath Device Controller.
[    1.841441] systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
[    1.841485] systemd[1]: Reached target local-fs.target - Local File Systems.
[    1.841517] systemd[1]: Reached target sysinit.target - System Initialization.
[    1.841548] systemd[1]: Reached target basic.target - Basic System.
[    1.841698] systemd[1]: coreos-enable-network.service - CoreOS Enable Network was skipped because all trigger condition checks failed.
[    1.841762] systemd[1]: nm-initrd.service was skipped because of a failed condition check (ConditionPathExists=/run/NetworkManager/initrd/neednet).
[    1.841792] systemd[1]: Reached target network.target - Network.
[    1.841821] systemd[1]: nm-wait-online-initrd.service was skipped because of a failed condition check (ConditionPathExists=/run/NetworkManager/initrd/neednet).
[    1.842763] systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook...
[    1.858250] systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook.
[    1.858413] systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
[    1.858451] systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
[    1.858482] systemd[1]: Reached target remote-fs.target - Remote File Systems.
[    1.858530] systemd[1]: coreos-livepxe-rootfs.service - Acquire live PXE rootfs image was skipped because of a failed condition check (ConditionPathExists=/run/ostree-live).
[    1.858572] systemd[1]: coreos-livepxe-persist-osmet.service - Persist osmet files (PXE) was skipped because of a failed condition check (ConditionPathExists=/run/ostree-live).
[    1.859542] systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook...
[    1.871697] systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook.
[    1.872743] systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-uuid/98823168-e7ca-11ec-9d60-02b27aba87e2...
[    1.888446] systemd-fsck[537]: /usr/sbin/fsck.xfs: XFS file system.
[    1.889605] systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-uuid/98823168-e7ca-11ec-9d60-02b27aba87e2.
[    2.204802] systemd[1]: Mounting sysroot.mount - /sysroot...
[    2.288459] SGI XFS with ACLs, security attributes, scrub, quota, no debug enabled
[    2.291514] XFS (nvme0n1p4): Mounting V5 Filesystem
[    2.863638] XFS (nvme0n1p4): Ending clean mount
[    2.924507] xfs filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff)
[    2.925404] systemd[1]: Mounted sysroot.mount - /sysroot.
[    2.926345] systemd[1]: Starting ostree-prepare-root.service - OSTree Prepare OS/...
[    2.928902] ostree-prepare-root[550]: preparing sysroot at /sysroot
[    2.930848] ostree-prepare-root[550]: Resolved OSTree target to: /sysroot/ostree/deploy/fedora-coreos/deploy/78a558e8231c8a7b038c4acf84137654a82367ca5815928ffdc931329ff9da64.0
[    2.931523] ostree-prepare-root[550]: filesystem at /sysroot currently writable: 1
[    2.931549] ostree-prepare-root[550]: sysroot.readonly configuration value: 1
[    2.934827] systemd[1]: sysroot-ostree-deploy-fedora\x2dcoreos-deploy-78a558e8231c8a7b038c4acf84137654a82367ca5815928ffdc931329ff9da64.0.mount: Deactivated successfully.
[    2.935287] systemd[1]: Finished ostree-prepare-root.service - OSTree Prepare OS/.
[    2.935476] systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System.
[    2.935545] systemd[1]: coreos-propagate-multipath-conf.service - CoreOS Propagate Multipath Configuration was skipped because of a failed condition check (ConditionKernelCommandLine=rd.multipath=default).
[    2.936363] systemd[1]: Starting initrd-parse-etc.service - Reload Configuration from the Real Root...
[    2.943939] systemd[1]: Reloading.
[    3.184411] multipathd[510]: exit (signal)
[    3.184477] multipathd[510]: --------shut down-------
[    3.184514] systemd[1]: Stopping multipathd.service - Device-Mapper Multipath Device Controller...
[    3.185579] systemd[1]: initrd-parse-etc.service: Deactivated successfully.
[    3.185753] systemd[1]: Finished initrd-parse-etc.service - Reload Configuration from the Real Root.
[    3.185966] systemd[1]: Reached target initrd-fs.target - Initrd File Systems.
[    3.186011] systemd[1]: Reached target initrd.target - Initrd Default Target.
[    3.186067] systemd[1]: dracut-mount.service - dracut mount hook was skipped because all trigger condition checks failed.
[    3.187076] systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook...
[    3.199048] systemd[1]: multipathd.service: Deactivated successfully.
[    3.199228] systemd[1]: Stopped multipathd.service - Device-Mapper Multipath Device Controller.
[    3.275688] dracut-pre-pivot[646]: 3.265401 | /etc/multipath.conf does not exist, blacklisting all devices.
[    3.275790] dracut-pre-pivot[646]: 3.265457 | You can run "/sbin/mpathconf --enable" to create
[    3.275818] dracut-pre-pivot[646]: 3.265461 | /etc/multipath.conf. See man mpathconf(8) for more details
[    3.282795] systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
[    3.283897] systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons...
[    3.294507] systemd[1]: Stopped target network.target - Network.
[    3.294623] systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes.
[    3.294743] systemd[1]: Stopped target timers.target - Timer Units.
[    3.294848] systemd[1]: dbus.socket: Deactivated successfully.
[    3.294968] systemd[1]: Closed dbus.socket - D-Bus System Message Bus Socket.
[    3.296155] systemd[1]: coreos-live-unmount-tmpfs-var.service - Unmount live /var if persistent /var is configured was skipped because of a failed condition check (ConditionPathExists=/run/ostree-live).
[    3.296262] systemd[1]: coreos-touch-run-agetty.service: Deactivated successfully.
[    3.296394] systemd[1]: Stopped coreos-touch-run-agetty.service - CoreOS: Touch /run/agetty.reload.
[    3.296572] systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
[    3.296696] systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
[    3.296898] systemd[1]: Stopped target initrd.target - Initrd Default Target.
[    3.297005] systemd[1]: Stopped target basic.target - Basic System.
[    3.297111] systemd[1]: Stopped target ignition-subsequent.target - Subsequent (Not Ignition) boot complete.
[    3.297212] systemd[1]: Stopped target ignition-diskful-subsequent.target - Ignition Subsequent Boot Disk Setup.
[    3.297313] systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device.
[    3.297410] systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System.
[    3.297513] systemd[1]: Stopped target paths.target - Path Units.
[    3.297615] systemd[1]: Stopped target remote-fs.target - Remote File Systems.
[    3.297714] systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems.
[    3.297810] systemd[1]: Stopped target slices.target - Slice Units.
[    3.297910] systemd[1]: Stopped target sockets.target - Socket Units.
[    3.298010] systemd[1]: Stopped target sysinit.target - System Initialization.
[    3.298113] systemd[1]: Stopped target local-fs.target - Local File Systems.
[    3.298213] systemd[1]: Stopped target local-fs-pre.target - Preparation for Local File Systems.
[    3.298310] systemd[1]: Stopped target swap.target - Swaps.
[    3.298387] systemd[1]: dracut-pre-mount.service: Deactivated successfully.
[    3.298514] systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook.
[    3.298694] systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
[    3.298758] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
[    3.320091] systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
[    3.320239] systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
[    3.320305] systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
[    3.360038] systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
[    3.360127] systemd[1]: dracut-initqueue.service: Deactivated successfully.
[    3.360279] systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook.
[    3.360495] systemd[1]: coreos-livepxe-rootfs.service - Acquire live PXE rootfs image was skipped because of a failed condition check (ConditionPathExists=/run/ostree-live).
[    3.360606] systemd[1]: systemd-sysctl.service: Deactivated successfully.
[    3.360728] systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables.
[    3.360858] systemd[1]: systemd-modules-load.service: Deactivated successfully.
[    3.360973] systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules.
[    3.361107] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
[    3.361230] systemd[1]: Stopped systemd-tmpfiles-setup.service - Create Volatile Files and Directories.
[    3.361354] systemd[1]: systemd-udev-settle.service: Deactivated successfully.
[    3.361486] systemd[1]: Stopped systemd-udev-settle.service - Wait for udev To Complete Device Initialization.
[    3.361612] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
[    3.361735] systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices.
[    3.361858] systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
[    3.361982] systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook.
[    3.362232] systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files...
[    3.365934] systemd[1]: initrd-cleanup.service: Deactivated successfully.
[    3.366100] systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons.
[    3.381667] systemd[1]: systemd-udevd.service: Deactivated successfully.
[    3.381849] systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files.
[    3.382203] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
[    3.382259] systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket.
[    3.382317] systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
[    3.382362] systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket.
[    3.382393] systemd[1]: dracut-pre-udev.service: Deactivated successfully.
[    3.382447] systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook.
[    3.382507] systemd[1]: dracut-cmdline.service: Deactivated successfully.
[    3.382563] systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook.
[    3.382617] systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
[    3.382672] systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
[    3.383656] systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database...
[    3.383715] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
[    3.383791] systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
[    3.383892] systemd[1]: kmod-static-nodes.service: Deactivated successfully.
[    3.383949] systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes.
[    3.384015] systemd[1]: systemd-sysusers.service: Deactivated successfully.
[    3.384103] systemd[1]: Stopped systemd-sysusers.service - Create System Users.
[    3.384300] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
[    3.384341] systemd[1]: Stopped systemd-vconsole-setup.service - Setup Virtual Console.
[    3.385685] systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully.
[    3.391961] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
[    3.392124] systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database.
[    3.392292] systemd[1]: Reached target initrd-switch-root.target - Switch Root.
[    3.393185] systemd[1]: Starting initrd-switch-root.service - Switch Root...
[    3.403536] systemd[1]: Switching root.
[    3.411354] systemd-journald[290]: Received SIGTERM from PID 1 (systemd).
[    3.685150] SELinux:  policy capability network_peer_controls=1
[    3.685156] SELinux:  policy capability open_perms=1
[    3.685158] SELinux:  policy capability extended_socket_class=1
[    3.685159] SELinux:  policy capability always_check_network=0
[    3.685160] SELinux:  policy capability cgroup_seclabel=1
[    3.685161] SELinux:  policy capability nnp_nosuid_transition=1
[    3.685162] SELinux:  policy capability genfs_seclabel_symlinks=1
[    3.685163] SELinux:  policy capability ioctl_skip_cloexec=0
[    3.794231] systemd[1]: Successfully loaded SELinux policy in 237.557ms.
[    3.840647] systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 30.708ms.
[    3.847781] systemd[1]: systemd v250.7-1.fc36 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)
[    3.847855] systemd[1]: Detected virtualization amazon.
[    3.847860] systemd[1]: Detected architecture arm64.
[    3.906189] systemd[1]: Failed to link BPF program. Assuming BPF is not available
[    3.941678] zram_generator::config[676]: No configuration found.
[    3.980730] coreos-platform-chrony: Updated chrony to use aws configuration /run/coreos-platform-chrony.conf
[    4.230222] systemd[1]: initrd-switch-root.service: Deactivated successfully.
[    4.230375] systemd[1]: Stopped initrd-switch-root.service - Switch Root.
[    4.230859] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
[    4.231270] systemd[1]: Created slice system-afterburn\x2dsshkeys.slice - Slice /system/afterburn-sshkeys.
[    4.231588] systemd[1]: Created slice system-getty.slice - Slice /system/getty.
[    4.231899] systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
[    4.232205] systemd[1]: Created slice system-sshd\x2dkeygen.slice - Slice /system/sshd-keygen.
[    4.232506] systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
[    4.232802] systemd[1]: Created slice user.slice - User and Session Slice.
[    4.232972] systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
[    4.233118] systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
[    4.233248] systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
[    4.233730] systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
[    4.233771] systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
[    4.233788] systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
[    4.233815] systemd[1]: Stopped target initrd-switch-root.target - Switch Root.
[    4.233836] systemd[1]: Stopped target initrd-fs.target - Initrd File Systems.
[    4.233848] systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System.
[    4.233865] systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
[    4.233919] systemd[1]: Reached target slices.target - Slice Units.
[    4.233944] systemd[1]: Reached target swap.target - Swaps.
[    4.233966] systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
[    4.234472] systemd[1]: Listening on dm-event.socket - Device-mapper event daemon FIFOs.
[    4.235533] systemd[1]: Listening on lvm2-lvmpolld.socket - LVM2 poll daemon socket.
[    4.235633] systemd[1]: multipathd.socket - multipathd control socket was skipped because of a failed condition check (ConditionPathExists=/etc/multipath.conf).
[    4.237565] systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket.
[    4.237730] systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe.
[    4.238936] systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
[    4.239335] systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
[    4.240134] systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket.
[    4.241533] systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System...
[    4.242942] systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
[    4.244307] systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
[    4.245698] systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
[    4.247865] systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
[    4.248168] systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because of a failed condition check (ConditionFirstBoot=true).
[    4.252506] systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
[    4.253816] systemd[1]: Starting lvm2-monitor.service - Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
[    4.255172] systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
[    4.256491] systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
[    4.257838] systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
[    4.257909] systemd[1]: ostree-prepare-root.service: Deactivated successfully.
[    4.257974] systemd[1]: Stopped ostree-prepare-root.service - OSTree Prepare OS/.
[    4.258153] systemd[1]: systemd-fsck-root.service: Deactivated successfully.
[    4.258200] systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device.
[    4.258308] systemd[1]: Stopped systemd-journald.service - Journal Service.
[    4.260245] systemd[1]: Starting systemd-journald.service - Journal Service...
[    4.270419] systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
[    4.271834] systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
[    4.273153] systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
[    4.274530] systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
[    4.277131] systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System.
[    4.277270] systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
[    4.277361] systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
[    4.277449] systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
[    4.277564] systemd[1]: Mounted tmp.mount - Temporary Directory /tmp.
[    4.278069] systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
[    4.281502] systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
[    4.281662] systemd[1]: Reached target network-pre.target - Preparation for Network.
[    4.285060] systemd[1]: modprobe@drm.service: Deactivated successfully.
[    4.285313] systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
[    4.285788] systemd[1]: modprobe@configfs.service: Deactivated successfully.
[    4.285944] systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
[    4.286260] systemd[1]: modprobe@fuse.service: Deactivated successfully.
[    4.286422] systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
[    4.286761] systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
[    4.288424] systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
[    4.290030] systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
[    4.290107] systemd[1]: iscsi-onboot.service - Special handling of early boot iSCSI sessions was skipped because of a failed condition check (ConditionDirectoryNotEmpty=/sys/class/iscsi_session).
[    4.293065] systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database...
[    4.294644] systemd[1]: Starting systemd-sysusers.service - Create System Users...
[    4.296204] systemd[1]: Started systemd-journald.service - Journal Service.
[    5.312870] kauditd_printk_skb: 135 callbacks suppressed
[    5.312873] audit: type=1130 audit(1658287573.079:144): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=lvm2-monitor comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.661512] audit: type=1130 audit(1658287575.429:145): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.677471] audit: type=1130 audit(1658287575.439:146): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=ostree-remount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.688420] systemd-journald[726]: Received client request to flush runtime journal.
[    7.692346] audit: type=1130 audit(1658287575.459:147): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.710814] audit: type=1130 audit(1658287575.479:148): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2duuid-96d6ad12\x2de7ca\x2d11ec\x2d8160\x2dcf3db43af328 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.719836] EXT4-fs (nvme0n1p3): mounted filesystem with ordered data mode. Quota mode: none.
[    7.730403] audit: type=1130 audit(1658287575.499:149): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dracut-shutdown comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.739644] audit: type=1130 audit(1658287575.499:150): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    7.883834] audit: type=1130 audit(1658287575.649:151): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    8.034531] audit: type=1130 audit(1658287575.799:152): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    8.037529] audit: type=1334 audit(1658287575.799:153): prog-id=56 op=LOAD
[    9.516793] EXT4-fs (nvme0n1p3): re-mounted. Quota mode: none.
[   10.911006] kauditd_printk_skb: 42 callbacks suppressed
[   10.911009] audit: type=1130 audit(1658287578.682:196): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=user-runtime-dir@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   10.947207] audit: type=1101 audit(1658287578.712:197): pid=1902 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_localuser acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   10.947240] audit: type=1103 audit(1658287578.712:198): pid=1902 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='op=PAM:setcred grantors=? acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
[   10.949040] audit: type=2300 audit(1658287578.712:199): pid=1902 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='pam: default-context=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 selected-context=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   10.949365] audit: type=1006 audit(1658287578.712:200): pid=1902 uid=0 subj=system_u:system_r:init_t:s0 old-auid=4294967295 auid=0 tty=(none) old-ses=4294967295 ses=1 res=1
[   10.949369] audit: type=1300 audit(1658287578.712:200): arch=c00000b7 syscall=64 success=yes exit=1 a0=7 a1=ffffce121af0 a2=1 a3=ffff8781e600 items=0 ppid=1 pid=1902 auid=0 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=1 comm="(systemd)" exe="/usr/lib/systemd/systemd" subj=system_u:system_r:init_t:s0 key=(null)
[   10.949379] audit: type=1327 audit(1658287578.712:200): proctitle="(systemd)"
[   10.952259] audit: type=1105 audit(1658287578.722:201): pid=1902 uid=0 auid=0 ses=1 subj=system_u:system_r:init_t:s0 msg='op=PAM:session_open grantors=pam_selinux,pam_selinux,pam_loginuid,pam_namespace,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   10.970770] audit: type=1334 audit(1658287578.732:202): prog-id=68 op=LOAD
[   10.970779] audit: type=1300 audit(1658287578.732:202): arch=c00000b7 syscall=280 success=yes exit=8 a0=5 a1=ffffd753f920 a2=78 a3=0 items=0 ppid=1 pid=1902 auid=0 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=1 comm="systemd" exe="/usr/lib/systemd/systemd" subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 key=(null)
[   14.703936] fbcon: Taking over console
[   19.578433] kauditd_printk_skb: 56 callbacks suppressed
[   19.578437] audit: type=1131 audit(1658287587.097:232): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   21.620628] audit: type=1104 audit(1658287589.136:233): pid=1923 uid=0 auid=0 ses=1 subj=system_u:system_r:init_t:s0 msg='op=PAM:setcred grantors=? acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
[   21.620637] audit: type=1131 audit(1658287589.136:234): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=user@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   21.646569] audit: type=1131 audit(1658287589.166:235): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=user-runtime-dir@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   24.805706] audit: type=1130 audit(1658287592.326:236): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=kdump comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   30.489655] audit: type=2404 audit(1658287598.006:237): pid=5103 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:sshd_t:s0-s0:c0.c1023 msg='op=destroy kind=server fp=SHA256:57:cf:9e:c4:09:35:7e:e8:ea:80:2c:18:4f:67:7a:06:84:1c:bd:59:3a:f3:25:a6:29:36:57:b8:e7:d8:67:08 direction=? spid=5103 suid=0  exe="/usr/sbin/sshd" hostname=? addr=? terminal=? res=success'
[   30.570911] audit: type=2407 audit(1658287598.086:238): pid=5102 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:sshd_t:s0-s0:c0.c1023 msg='op=start direction=from-server cipher=aes256-gcm@openssh.com ksize=256 mac=<implicit> pfs=curve25519-sha256 spid=5103 suid=74 rport=6475 laddr=172.30.0.176 lport=22  exe="/usr/sbin/sshd" hostname=? addr=111.199.184.174 terminal=? res=success'
[   30.570988] audit: type=2407 audit(1658287598.086:239): pid=5102 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:sshd_t:s0-s0:c0.c1023 msg='op=start direction=from-client cipher=aes256-gcm@openssh.com ksize=256 mac=<implicit> pfs=curve25519-sha256 spid=5103 suid=74 rport=6475 laddr=172.30.0.176 lport=22  exe="/usr/sbin/sshd" hostname=? addr=111.199.184.174 terminal=? res=success'
[   33.154716] audit: type=1100 audit(1658287600.666:240): pid=5102 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:sshd_t:s0-s0:c0.c1023 msg='op=pubkey acct="core" exe="/usr/sbin/sshd" hostname=? addr=111.199.184.174 terminal=ssh res=failed'
[   33.250310] audit: type=1100 audit(1658287600.766:241): pid=5102 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:sshd_t:s0-s0:c0.c1023 msg='op=pubkey_auth grantors=auth-key acct="core" exe="/usr/sbin/sshd" hostname=? addr=111.199.184.174 terminal=? res=success'
[   33.250322] audit: type=2404 audit(1658287600.766:242): pid=5102 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:sshd_t:s0-s0:c0.c1023 msg='op=negotiate kind=auth-key fp=SHA256:08:a1:9d:33:2a:88:bb:ef:ff:9b:62:24:96:ae:8b:19:92:f2:27:07:60:a0:5f:67:7f:28:68:18:d2:a2:84:e4 exe="/usr/sbin/sshd" hostname=? addr=111.199.184.174 terminal=? res=success'
[   33.278185] audit: type=1101 audit(1658287600.796:243): pid=5102 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:sshd_t:s0-s0:c0.c1023 msg='op=PAM:accounting grantors=pam_unix,pam_localuser acct="core" exe="/usr/sbin/sshd" hostname=111.199.184.174 addr=111.199.184.174 terminal=ssh res=success'
[   33.278672] audit: type=2404 audit(1658287600.796:244): pid=5102 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:sshd_t:s0-s0:c0.c1023 msg='op=destroy kind=session fp=? direction=both spid=5103 suid=74 rport=6475 laddr=172.30.0.176 lport=22  exe="/usr/sbin/sshd" hostname=? addr=111.199.184.174 terminal=? res=success'
[   33.281484] audit: type=1103 audit(1658287600.796:245): pid=5102 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:sshd_t:s0-s0:c0.c1023 msg='op=PAM:setcred grantors=pam_env,pam_localuser,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=111.199.184.174 addr=111.199.184.174 terminal=ssh res=success'
[   33.281568] audit: type=1006 audit(1658287600.796:246): pid=5102 uid=0 subj=system_u:system_r:sshd_t:s0-s0:c0.c1023 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=2 res=1

@pfliu
Copy link

pfliu commented Jul 20, 2022

I will try with the latest upstream kernel, then I can report the result to the upstream.

@pfliu
Copy link

pfliu commented Aug 2, 2022

It turns out that in linux kernel, the function univ8250_console_match() can not match the driver with the device reported by ACPI SPCR table.

The mismatch happens on the following statements:
if ((iotype == UPIO_MEM || iotype == UPIO_MEM16 ||
iotype == UPIO_MEM32 || iotype == UPIO_MEM32BE)
&& (port->mapbase != addr))

Where @addr is the one reported in SPCR (0x90a0000), while port->mapbase == 0x8011c000.

Consequently, the pci serial driver failed to register the serial port as the default console.

I think this should be an AWS platform bug

@ozbenh
Copy link

ozbenh commented Aug 2, 2022

First, note about the "EFI stub: ERROR: FIRMWARE BUG: kernel image not aligned on 64k boundary" error.. this is a bootloader bug (old grub2 ?) It's been fixed in Fedora/RH afaik

About the "mapbase" mismatch, that could indicate that the kernel is incorrectly remapping the PCI device containing the UART, thus causing that mismatch. It should be prevented to do so by the "PCI Boot Configuration" _DSM Function" in ACPI.

Can you tell me more about the instance type you used ? I'll see if I can track this down

@ozbenh
Copy link

ozbenh commented Aug 2, 2022

So....

For (bad) historical reasons, the arm64 kernel will re-assign all PCI(e) devices at boot, it will not try to honor the existing firmware assignments at all, unless an ACPI _DSM entry tells it to. This behaviour is different from x86. I remember some debates about this ages ago, and I think the excuse for not fixing that was the existence of a couple of platforms with broken UEFI firmwares...

So the kernel reassigns everything, and thus the address no longer matches. It's fundamentally a kernel issue. I'll work with EC2 to see if we can get the _DSM firmware entry changed, but I have suspicions that this is too disruptive to do on existing instance types since it will completely change their PCI resources layout. We do have it on metal, that I know.

So I think we need to look at a more involved kernel fix. This is fundamentally a Linux issue. It should probably keep track of the console address in a form more appropriate than an ASCII string ... and have some kind of hook into the PCI code to "match" it with a discovered device and then adjust the address as the device gets remapped.

@pfliu
Copy link

pfliu commented Aug 2, 2022

@ozbenh, appreciate for looking into this issue.

I used c6g.xlarge.

I have not seen any connection between the two address numbers 0x90a0000 and 0x8011c000. I though at least both of them should have 0xc000 as the offset inside a pci bar.

According to 1, The base address of the Serial Port register set is
described using the ACPI Generic Address Structure. Then jumping onto
2, 5.2.3.2 Generic Address Structure, Table 5-25 Generic Address
Structure (GAS). I think here the Address Space ID should be "0x6
PciBarTarget", but Table 5-26 Address Space Format does describe this
kind of address. (Should it be the relative address to the PCI BAR
base address?)

@dustymabe
Copy link
Member Author

Hi @ozbenh 👋

First, note about the "EFI stub: ERROR: FIRMWARE BUG: kernel image not aligned on 64k boundary" error.. this is a bootloader bug (old grub2 ?) It's been fixed in Fedora/RH afaik

In Fedora CoreOS here we're just using the latest GRUB2 in Fedora. Do you think the bug resurfaced?

@ozbenh
Copy link

ozbenh commented Aug 3, 2022

From which Fedora version ? This patch in Fedora's grub2 should fix it [PATCH] arm64: Fix EFI loader kernel image allocation , unless a separate problem causes the kernel image to not expose the right alignment requirement in the PE header...

@ozbenh
Copy link

ozbenh commented Aug 3, 2022

@ozbenh, appreciate for looking into this issue.

I used c6g.xlarge.

I have not seen any connection between the two address numbers 0x90a0000 and 0x8011c000. I though at least both of them should have 0xc000 as the offset inside a pci bar.

They don't need to be related. One is what UEFI assigned at boot, the other is what Linux assigned. The BAR is only 4k in size so there isn't even a 0xc000 offset there, that's just where it happened to be allocated.

According to 1, The base address of the Serial Port register set is described using the ACPI Generic Address Structure. Then jumping onto 2, 5.2.3.2 Generic Address Structure, Table 5-25 Generic Address Structure (GAS). I think here the Address Space ID should be "0x6 PciBarTarget", but Table 5-26 Address Space Format does describe this kind of address. (Should it be the relative address to the PCI BAR base address?)

Yeah though Linux can't really deal with these I think in any saner way either :-) I've started a conversation in the linux-pci and linux-arm-kernel mailing lists about this "arm64 PCI resource allocation issue", let's see where that goes.

@dustymabe
Copy link
Member Author

Sorry for the delay in getting back to you on this.

From which Fedora version ? This patch in Fedora's grub2 should fix it [PATCH] arm64: Fix EFI loader kernel image allocation , unless a separate problem causes the kernel image to not expose the right alignment requirement in the PE header...

I think you're referring to https://src.fedoraproject.org/rpms/grub2/blob/rawhide/f/0190-arm64-Fix-EFI-loader-kernel-image-allocation.patch

Yeah I'm looking at some console output from tests that ran today and I don't see EFI stub: ERROR: FIRMWARE BUG: kernel image not aligned on 64k boundary

This is kind of an old issue so some of the logs are from systems before the patch you mentioned was implemented.

@ozbenh
Copy link

ozbenh commented Aug 8, 2022

Right, I wrote that patch for Amazon Linux initially and sent it to the various distros, it took a while for it to percolate. Sadly that code is still hacked to death by the original shim support path so the patch can't really be upstreamed (upstream loader just uses UEFI load image).

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
jira for syncing to jira kind/bug
Projects
None yet
Development

No branches or pull requests

9 participants