Run with :
build/tests/venv/bin/avocado --show=app,console run -t machine:powernv9
build/tests/avocado/boot_linux_console.py
* 6.2
...
console: [ 1.559904] PCI: CLS 0 bytes, default 128
/console: [ 8.830245] Initialise system trusted keyrings
console: [ 8.832347] Key type blacklist registered
console: [ 8.834558] workingset: timestamp_bits=54 max_order=14
bucket_order=0
console: [ 9.073051] integrity: Platform Keyring initialized
console: [ 9.073586] Key type asymmetric registered
console: [ 9.074025] Asymmetric key parser 'x509' registered
console: [ 9.075359] Block layer SCSI generic (bsg) driver version 0.4
loaded (major 251)
console: [ 9.095115] IPMI message handler: version 39.2
console: [ 9.096161] ipmi device interface
console: [ 9.514308] ipmi-powernv ibm,opal:ipmi: IPMI message handler: Found
new BMC (man_id: 0x000000, prod_id: 0x0000, dev_id: 0x20)
-console: [ 10.171273] IPMI Watchdog: driver initialized
\console: [ 10.974462] hvc0: raw protocol on /ibm,opal/consoles/serial@0
(boot console)
console: [ 10.975059] hvc0: No interrupts property, using OPAL event
console: [ 10.989699] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
console: [ 11.156033] brd: module loaded
console: [ 11.235965] loop: module loaded
console: [ 11.249922] libphy: Fixed MDIO Bus: probed
console: [ 11.254128] i2c /dev entries driver
console: [ 11.255782] powernv-cpufreq: ibm,pstate-min node not found
console: [ 11.256134] powernv-cpufreq: Platform driver disabled. System does
not support PState control
console: [ 11.273326] ipip: IPv4 and MPLS over IPv4 tunneling driver
console: [ 11.303989] NET: Registered protocol family 10
console: [ 11.323651] Segment Routing with IPv6
console: [ 11.325267] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
console: [ 11.335866] NET: Registered protocol family 17
console: [ 11.336900] Key type dns_resolver registered
console: [ 11.337358] secvar-sysfs: secvar: failed to retrieve secvar
operations.
console: [ 11.337877] drmem: No dynamic reconfiguration memory found
console: [ 11.341767] Loading compiled-in X.509 certificates
console: [ 11.362272] Loaded X.509 cert 'Build time autogenerated kernel key:
987b64c96d830fe42d02bbf502e028ebe85c2b4e'
console: [ 11.667162] Key type encrypted registered
console: [ 11.674616] ima: No TPM chip found, activating TPM-bypass!
console: [ 11.676949] ima: Allocated hash algorithm: sha256
console: [ 11.682967] Secure boot mode disabled
console: [ 11.683726] Trusted boot mode disabled
console: [ 11.684075] ima: No architecture policies found
console: [ 11.748319] Freeing unused kernel memory: 13696K
console: [ 11.748717] This architecture does not have kernel memory
protection.
console: [ 11.750290] Run /init as init process
/console: [ 13.712943] udevd[74]: starting version 3.2.9
console: [ 13.731186] random: udevd: uninitialized urandom read (16 bytes
read)
console: [ 13.735595] random: udevd: uninitialized urandom read (16 bytes
read)
console: [ 13.737907] random: udevd: uninitialized urandom read (16 bytes
read)
-console: [ 13.832821] udevd[75]: starting eudev-3.2.9
|console: [ 16.333618] PTP clock support registered
console: [ 16.601330] e1000e: Intel(R) PRO/1000 Network Driver
console: [ 16.601791] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
console: [ 16.607410] e1000e 0001:02:03.0: enabling device (0100 -> 0102)
console: [ 16.617706] e1000e 0001:02:03.0: Interrupt Throttling Rate
(ints/sec) set to dynamic conservative mode
console: [ 16.697120] usbcore: registered new interface driver usbfs
console: [ 16.699742] usbcore: registered new interface driver hub
console: [ 16.702753] usbcore: registered new device driver usb
console: [ 16.762288] nvme nvme0: pci function 0002:01:00.0
console: [ 16.763998] nvme 0002:01:00.0: enabling device (0100 -> 0102)
/console: [ 16.881654] rtc-opal opal-rtc: registered as rtc0
console: [ 17.032286] rtc-opal opal-rtc: setting system clock to
2021-12-21T03:38:58 UTC (1640057938)
console: [ 17.052213] nvme nvme0: 1/0/0 default/read/poll queues
console: [ 17.055242] e1000e 0001:02:03.0 0001:02:03.0 (uninitialized):
registered PHC clock
console: [ 17.213376] e1000e 0001:02:03.0 eth0: (PCI Express:2.5GT/s:Width
x1) 52:54:00:12:34:57
console: [ 17.213982] e1000e 0001:02:03.0 eth0: Intel(R) PRO/1000 Network
Connection
console: [ 17.214655] e1000e 0001:02:03.0 eth0: MAC: 3, PHY: 8, PBA No:
000000-000
console: [ 17.399217] xhci_hcd 0001:02:02.0: xHCI Host Controller
console: [ 17.400904] xhci_hcd 0001:02:02.0: new USB bus registered, assigned
bus number 1
console: [ 17.408566] xhci_hcd 0001:02:02.0: hcc params 0x00080001 hci
version 0x100 quirks 0x0000000000000014
-console: [ 17.855122] hub 1-0:1.0: USB hub found
console: [ 17.901064] hub 1-0:1.0: 4 ports detected
console: [ 17.997599] xhci_hcd 0001:02:02.0: xHCI Host Controller
console: [ 17.998280] xhci_hcd 0001:02:02.0: new USB bus registered, assigned
bus number 2
console: [ 17.999035] xhci_hcd 0001:02:02.0: Host supports USB 3.0 SuperSpeed
console: [ 18.104390] usb usb2: We don't know the algorithms for LPM for this
host, disabling LPM.
console: [ 18.311117] hub 2-0:1.0: USB hub found
console: [ 18.481076] hub 2-0:1.0: 4 ports detected
console: [ 18.676328] 1 fixed-partitions partitions found on MTD device
flash@0
console: [ 18.676887] Creating 1 MTD partitions on "flash@0":
PASS (42.00 s)
RESULTS : PASS 1 | ERROR 0 | FAIL 0 | SKIP 0 | WARN 0 | INTERRUPT 0 | CANCEL 0
JOB TIME : 42.60 s
* 7.0
....
console: [ 3.669714] PCI: CLS 0 bytes, default 128
\console: [ 38.778763] Initialise system trusted keyrings
console: [ 38.781487] Key type blacklist registered
console: [ 38.786515] workingset: timestamp_bits=54 max_order=14
bucket_order=0
|console: [ 39.406270] integrity: Platform Keyring initialized
console: [ 39.407161] Key type asymmetric registered
console: [ 39.407879] Asymmetric key parser 'x509' registered
console: [ 39.411112] Block layer SCSI generic (bsg) driver version 0.4
loaded (major 251)
console: [ 39.458660] IPMI message handler: version 39.2
console: [ 39.461141] ipmi device interface
console: [ 39.864578] ipmi-powernv ibm,opal:ipmi: IPMI message handler: Found
new BMC (man_id: 0x000000, prod_id: 0x0000, dev_id: 0x20)
/console: [ 40.518598] IPMI Watchdog: driver initialized
\console: [ 42.679606] hvc0: raw protocol on /ibm,opal/consoles/serial@0
(boot console)
console: [ 42.680490] hvc0: No interrupts property, using OPAL event
console: [ 42.718661] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
console: [ 43.131333] brd: module loaded
|console: [ 43.331465] loop: module loaded
console: [ 43.366052] libphy: Fixed MDIO Bus: probed
console: [ 43.375750] i2c /dev entries driver
console: [ 43.380135] powernv-cpufreq: ibm,pstate-min node not found
console: [ 43.380691] powernv-cpufreq: Platform driver disabled. System does
not support PState control
console: [ 43.423981] ipip: IPv4 and MPLS over IPv4 tunneling driver
console: [ 43.497912] NET: Registered protocol family 10
console: [ 43.540218] Segment Routing with IPv6
console: [ 43.542407] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
console: [ 43.568856] NET: Registered protocol family 17
console: [ 43.570360] Key type dns_resolver registered
console: [ 43.571289] secvar-sysfs: secvar: failed to retrieve secvar
operations.
console: [ 43.572075] drmem: No dynamic reconfiguration memory found
console: [ 43.579066] Loading compiled-in X.509 certificates
console: [ 43.621261] Loaded X.509 cert 'Build time autogenerated kernel key:
987b64c96d830fe42d02bbf502e028ebe85c2b4e'
console: [ 44.052308] Key type encrypted registered
console: [ 44.061477] ima: No TPM chip found, activating TPM-bypass!
console: [ 44.064700] ima: Allocated hash algorithm: sha256
console: [ 44.072905] Secure boot mode disabled
console: [ 44.074908] Trusted boot mode disabled
console: [ 44.075445] ima: No architecture policies found
/console: [ 44.157077] Freeing unused kernel memory: 13696K
console: [ 44.157843] This architecture does not have kernel memory
protection.
console: [ 44.160154] Run /init as init process
|console: [ 47.520497] udevd[74]: starting version 3.2.9
console: [ 47.555948] random: udevd: uninitialized urandom read (16 bytes
read)
console: [ 47.563916] random: udevd: uninitialized urandom read (16 bytes
read)
console: [ 47.567528] random: udevd: uninitialized urandom read (16 bytes
read)
console: [ 47.729266] udevd[75]: starting eudev-3.2.9
/console: [ 49.004222] urandom_read: 5 callbacks suppressed
console: [ 49.004347] random: udevd: uninitialized urandom read (16 bytes
read)
|console: [ 59.758967] PTP clock support registered
/console: [ 60.749571] e1000e: Intel(R) PRO/1000 Network Driver
console: [ 60.750176] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
console: [ 60.763411] e1000e 0001:02:03.0: enabling device (0100 -> 0102)
console: [ 60.775134] e1000e 0001:02:03.0: Interrupt Throttling Rate
(ints/sec) set to dynamic conservative mode
console: [ 60.840369] nvme nvme0: pci function 0002:01:00.0
console: [ 60.842333] nvme 0002:01:00.0: enabling device (0100 -> 0102)
console: [ 61.001290] usbcore: registered new interface driver usbfs
console: [ 61.008092] usbcore: registered new interface driver hub
console: [ 61.013320] usbcore: registered new device driver usb
-console: [ 61.260003] e1000e 0001:02:03.0 0001:02:03.0 (uninitialized):
registered PHC clock
console: [ 61.439568] e1000e 0001:02:03.0 eth0: (PCI Express:2.5GT/s:Width
x1) 52:54:00:12:34:57
console: [ 61.440440] e1000e 0001:02:03.0 eth0: Intel(R) PRO/1000 Network
Connection
console: [ 61.441473] e1000e 0001:02:03.0 eth0: MAC: 3, PHY: 8, PBA No:
000000-000
console: [ 61.453243] nvme nvme0: 1/0/0 default/read/poll queues
console: [ 61.852525] rtc-opal opal-rtc: registered as rtc0
console: [ 62.041247] rtc-opal opal-rtc: setting system clock to
2021-12-21T03:39:09 UTC (1640057949)
console: [ 62.175046] xhci_hcd 0001:02:02.0: xHCI Host Controller
console: [ 62.176904] xhci_hcd 0001:02:02.0: new USB bus registered, assigned
bus number 1
console: [ 62.188065] xhci_hcd 0001:02:02.0: hcc params 0x00080001 hci
version 0x100 quirks 0x0000000000000014
\console: [ 62.849997] hub 1-0:1.0: USB hub found
console: [ 62.998795] hub 1-0:1.0: 4 ports detected
|console: [ 63.232067] xhci_hcd 0001:02:02.0: xHCI Host Controller
console: [ 63.233192] xhci_hcd 0001:02:02.0: new USB bus registered, assigned
bus number 2
console: [ 63.234507] xhci_hcd 0001:02:02.0: Host supports USB 3.0 SuperSpeed
console: [ 63.468360] usb usb2: We don't know the algorithms for LPM for this
host, disabling LPM.
console: [ 63.786654] hub 2-0:1.0: USB hub found
console: [ 63.908912] hub 2-0:1.0: 4 ports detected
/console: [ 65.069424] 1 fixed-partitions partitions found on MTD device
flash@0
console: [ 65.070221] Creating 1 MTD partitions on "flash@0":
PASS (89.13 s)
RESULTS : PASS 1 | ERROR 0 | FAIL 0 | SKIP 0 | WARN 0 | INTERRUPT 0 | CANCEL 0
JOB TIME : 89.77 s
Emulation is twice as slow. That's not good.
It might be the case that the performance gain is enough to make these tests
happy again,
although my initial guess is that there's something during boot that is
starting the PMU and
leaving it running.
MMCR0 is set to 0 when kernel is started. I guess the modeling is wrong in
pmc_is_inactive() or pmc_get_event().