ubuntu-kernel-tests

Test Wakeup Tracer / RT Tracer fails in ubuntu_kernel_selftests

Bug #1873054 reported by Sean Feole on 2020-04-15

This bug affects 2 people

	Status	Importance	Assigned to
ubuntu-kernel-tests	Confirmed	Undecided	Unassigned
linux (Ubuntu)	Incomplete	Undecided	Unassigned
Focal	Incomplete	Undecided	Unassigned

Bug Description

In Focal,

The following tests fail on baremetal/amd64:

Full output below.

04/12 03:25:07 DEBUG| 04/12 03:25:07 DEBUG| 04/12 03:25:07 DEBUG| 04/12 03:25:17 DEBUG| 04/12 03:25:21 DEBUG| 04/12 03:25:21 DEBUG| 04/12 03:25:22 DEBUG| 04/12 03:25:22 DEBUG| 04/12 03:25:23 DEBUG| 04/12 03:25:23 DEBUG| 04/12 03:25:24 DEBUG| 04/12 03:25:24 DEBUG| 04/12 03:25:25 DEBUG| 04/12 03:25:26 DEBUG| 04/12 03:25:27 DEBUG| 04/12 03:25:27 DEBUG| 04/12 03:25:28 DEBUG| 04/12 03:25:30 DEBUG| 04/12 03:25:35 DEBUG| 04/12 03:25:37 DEBUG| 04/12 03:25:40 DEBUG| 04/12 03:25:41 DEBUG| 04/12 03:25:43 DEBUG| 04/12 03:25:48 DEBUG| 04/12 03:25:49 DEBUG| 04/12 03:25:53 DEBUG| 04/12 03:25:59 DEBUG| 04/12 03:26:03 DEBUG| 04/12 03:26:07 DEBUG| 04/12 03:26:07 DEBUG| 04/12 03:26:11 DEBUG| 04/12 03:26:13 DEBUG| 04/12 03:26:13 DEBUG| 04/12 03:26:14 DEBUG| 04/12 03:26:14 DEBUG| 04/12 03:26:15 DEBUG| 04/12 03:26:15 DEBUG| 04/12 03:26:16 DEBUG| 04/12 03:26:18 DEBUG| 04/12 03:26:20 DEBUG| 04/12 03:26:20 DEBUG| 04/12 03:26:21 DEBUG| 04/12 03:26:23 DEBUG| 04/12 03:26:24 DEBUG| 04/12 03:26:25 DEBUG| 04/12 03:26:26 DEBUG| 04/12 03:26:27 DEBUG| 04/12 03:26:55 DEBUG| 04/12 03:26:56 DEBUG| 04/12 03:26:56 DEBUG| 04/12 03:26:57 DEBUG| 04/12 03:27:36 DEBUG| 04/12 03:27:37 DEBUG| 04/12 03:27:39 DEBUG| 04/12 03:27:41 DEBUG| 04/12 03:27:44 DEBUG| 04/12 03:27:45 DEBUG| 04/12 03:27:50 DEBUG| 04/12 03:27:55 DEBUG| 04/12 03:28:00 DEBUG| 04/12 03:28:03 DEBUG| 04/12 03:28:08 DEBUG| 04/12 03:28:09 DEBUG| 04/12 03:28:14 DEBUG| 04/12 03:28:15 DEBUG| 04/12 03:28:16 DEBUG| 04/12 03:28:17 DEBUG| 04/12 03:28:17 DEBUG| 04/12 03:28:18 DEBUG| 04/12 03:28:19 DEBUG| 04/12 03:28:20 DEBUG| 04/12 03:28:22 DEBUG| 04/12 03:28:23 DEBUG| 04/12 03:28:24 DEBUG| 04/12 03:28:31 DEBUG| 04/12 03:28:35 DEBUG| 04/12 03:28:35 DEBUG| 04/12 03:28:36 DEBUG| 04/12 03:28:37 DEBUG| 04/12 03:28:37 DEBUG| 04/12 03:28:38 DEBUG| 04/12 03:28:39 DEBUG| 04/12 03:28:41 DEBUG| 04/12 03:28:45 DEBUG| 04/12 03:28:48 DEBUG| 04/12 03:28:49 DEBUG| 04/12 03:28:50 DEBUG| 04/12 03:28:51 DEBUG| 04/12 03:28:52 DEBUG| 04/12 03:28:52 DEBUG| 04/12 03:28:53 DEBUG| 04/12 03:28:53 DEBUG| 04/12 03:28:53 DEBUG| 04/12 03:28:53 DEBUG| 04/12 03:28:53 DEBUG| 04/12 03:28:53 DEBUG| 04/12 03:28:53 DEBUG| 04/12 03:28:53 DEBUG| 04/12 03:28:53 DEBUG| 04/12 03:28:53 DEBUG| ---
ProblemType: Bug
AlsaDevices:
total 0
crw-rw---- crw-rw---- AplayDevices: ApportVersion: 2.20.11-0ubuntu26
Architecture: amd64
ArecordDevices: AudioDevicesInUse: CasperMD5CheckResult: skip
DistroRelease: Ubuntu 20.04
IwConfig: Error: Lsusb:
Bus 002 Bus 002 Bus 001 Bus 001 Bus 001 Lsusb-t:
/: Bus |__ /: Bus |__ &nb MachineType: Package: linux (not installed)
PciMultimedia: utils:0153| [stdout] # selftests: ftrace: ftracetest
utils:0153| [stdout] # === Ftrace unit tests ===
utils:0153| [stdout] # [1] Basic trace file check [PASS]
utils:0153| [stdout] # [2] Basic test for tracers [PASS]
utils:0153| [stdout] # [3] Basic trace clock test [PASS]
utils:0153| [stdout] # [4] Basic event tracing check [PASS]
utils:0153| [stdout] # [5] Change the ringbuffer size [PASS]
utils:0153| [stdout] # [6] Snapshot and tracing setting [PASS]
utils:0153| [stdout] # [7] trace_pipe and trace_marker [PASS]
utils:0153| [stdout] # [8] Generic dynamic event - add/remove kprobe events [PASS]
utils:0153| [stdout] # [9] Generic dynamic event - add/remove synthetic events [PASS]
utils:0153| [stdout] # [10] Generic dynamic event - selective clear (compatibility) [PASS]
utils:0153| [stdout] # [11] Generic dynamic event - generic clear event [PASS]
utils:0153| [stdout] # [12] event tracing - enable/disable with event level files [PASS]
utils:0153| [stdout] # [13] event tracing - restricts events based on pid [PASS]
utils:0153| [stdout] # [14] event tracing - enable/disable with subsystem level files [PASS]
utils:0153| [stdout] # [15] event tracing - enable/disable with top level files [PASS]
utils:0153| [stdout] # [16] Test trace_printk from module [PASS]
utils:0153| [stdout] # [17] ftrace - function graph filters with stack tracer [PASS]
utils:0153| [stdout] # [18] ftrace - function graph filters [PASS]
utils:0153| [stdout] # [19] ftrace - function pid filters [PASS]
utils:0153| [stdout] # [20] ftrace - stacktrace filter command [PASS]
utils:0153| [stdout] # [21] ftrace - function trace with cpumask [PASS]
utils:0153| [stdout] # [22] ftrace - test for function event triggers [PASS]
utils:0153| [stdout] # [23] ftrace - function trace on module [PASS]
utils:0153| [stdout] # [24] ftrace - function profiling [PASS]
utils:0153| [stdout] # [25] ftrace - function profiler with function tracing [PASS]
utils:0153| [stdout] # [26] ftrace - test reading of set_ftrace_filter [PASS]
utils:0153| [stdout] # [27] ftrace - test for function traceon/off triggers [PASS]
utils:0153| [stdout] # [28] ftrace - test tracing error log support [PASS]
utils:0153| [stdout] # [29] Test creation and deletion of trace instances while setting an event [PASS]
utils:0153| [stdout] # [30] Test creation and deletion of trace instances [PASS]
utils:0153| [stdout] # [31] Kprobe dynamic event - adding and removing [PASS]
utils:0153| [stdout] # [32] Kprobe dynamic event - busy event check [PASS]
utils:0153| [stdout] # [33] Kprobe dynamic event with arguments [PASS]
utils:0153| [stdout] # [34] Kprobe event with comm arguments [PASS]
utils:0153| [stdout] # [35] Kprobe event string type argument [PASS]
utils:0153| [stdout] # [36] Kprobe event symbol argument [PASS]
utils:0153| [stdout] # [37] Kprobe event argument syntax [PASS]
utils:0153| [stdout] # [38] Kprobes event arguments with types [PASS]
utils:0153| [stdout] # [39] Kprobe event user-memory access [PASS]
utils:0153| [stdout] # [40] Kprobe event auto/manual naming [PASS]
utils:0153| [stdout] # [41] Kprobe dynamic event with function tracer [PASS]
utils:0153| [stdout] # [42] Create/delete multiprobe on kprobe event [PASS]
utils:0153| [stdout] # [43] Kprobe event parser error log check [PASS]
utils:0153| [stdout] # [44] Kretprobe dynamic event with arguments [PASS]
utils:0153| [stdout] # [45] Kretprobe dynamic event with maxactive [PASS]
utils:0153| [stdout] # [46] Register/unregister many kprobe events [PASS]
utils:0153| [stdout] # [47] Kprobe dynamic event - adding and removing [PASS]
utils:0153| [stdout] # [48] Uprobe event parser error log check [PASS]
utils:0153| [stdout] # [49] test for the preemptirqsoff tracer [UNSUPPORTED]
utils:0153| [stdout] # [50] Meta-selftest [PASS]
utils:0153| [stdout] # [51] Test wakeup tracer [FAIL]
utils:0153| [stdout] # [52] Test wakeup RT tracer [FAIL]
utils:0153| [stdout] # [53] event trigger - test inter-event histogram trigger expected fail actions [XFAIL]
utils:0153| [stdout] # [54] event trigger - test field variable support [PASS]
utils:0153| [stdout] # [55] event trigger - test multiple actions on hist trigger [PASS]
utils:0153| [stdout] # [56] event trigger - test inter-event histogram trigger onchange action [PASS]
utils:0153| [stdout] # [57] event trigger - test inter-event histogram trigger onmatch action [PASS]
utils:0153| [stdout] # [58] event trigger - test inter-event histogram trigger onmatch-onmax action [PASS]
utils:0153| [stdout] # [59] event trigger - test inter-event histogram trigger onmax action [PASS]
utils:0153| [stdout] # [60] event trigger - test inter-event histogram trigger snapshot action [PASS]
utils:0153| [stdout] # [61] event trigger - test synthetic_events syntax parser [PASS]
utils:0153| [stdout] # [62] event trigger - test inter-event histogram trigger trace action [PASS]
utils:0153| [stdout] # [63] event trigger - test event enable/disable trigger [PASS]
utils:0153| [stdout] # [64] event trigger - test trigger filter [PASS]
utils:0153| [stdout] # [65] event trigger - test histogram modifiers [PASS]
utils:0153| [stdout] # [66] event trigger - test multiple histogram triggers [PASS]
utils:0153| [stdout] # [67] event trigger - test snapshot-trigger [PASS]
utils:0153| [stdout] # [68] event trigger - test stacktrace-trigger [PASS]
utils:0153| [stdout] # [69] trace_marker trigger - test snapshot trigger [PASS]
utils:0153| [stdout] # [70] trace_marker trigger - test histogram with synthetic event against kernel event [PASS]
utils:0153| [stdout] # [71] trace_marker trigger - test histogram with synthetic event [PASS]
utils:0153| [stdout] # [72] event trigger - test traceon/off trigger [PASS]
utils:0153| [stdout] # [73] (instance) Basic test for tracers [PASS]
utils:0153| [stdout] # [74] (instance) Basic trace clock test [PASS]
utils:0153| [stdout] # [75] (instance) Change the ringbuffer size [PASS]
utils:0153| [stdout] # [76] (instance) Snapshot and tracing setting [PASS]
utils:0153| [stdout] # [77] (instance) trace_pipe and trace_marker [PASS]
utils:0153| [stdout] # [78] (instance) event tracing - enable/disable with event level files [PASS]
utils:0153| [stdout] # [79] (instance) event tracing - restricts events based on pid [PASS]
utils:0153| [stdout] # [80] (instance) event tracing - enable/disable with subsystem level files [PASS]
utils:0153| [stdout] # [81] (instance) ftrace - stacktrace filter command [PASS]
utils:0153| [stdout] # [82] (instance) ftrace - test for function event triggers [PASS]
utils:0153| [stdout] # [83] (instance) ftrace - test for function traceon/off triggers [PASS]
utils:0153| [stdout] # [84] (instance) event trigger - test event enable/disable trigger [PASS]
utils:0153| [stdout] # [85] (instance) event trigger - test trigger filter [PASS]
utils:0153| [stdout] # [86] (instance) event trigger - test histogram modifiers [PASS]
utils:0153| [stdout] # [87] (instance) event trigger - test multiple histogram triggers [PASS]
utils:0153| [stdout] # [88] (instance) trace_marker trigger - test snapshot trigger [PASS]
utils:0153| [stdout] #
utils:0153| [stdout] #
utils:0153| [stdout] # # of passed: 84
utils:0153| [stdout] # # of failed: 2
utils:0153| [stdout] # # of unresolved: 0
utils:0153| [stdout] # # of untested: 0
utils:0153| [stdout] # # of unsupported: 1
utils:0153| [stdout] # # of xfailed: 1
utils:0153| [stdout] # # of undefined(test bug): 0
utils:0153| [stdout] not ok 1 selftests: ftrace: ftracetest # exit=1
1 root audio 116, 1 Apr 15 19:06 seq
1 root audio 116, 33 Apr 15 19:06 timer
Error: [Errno 2] No such file or directory: 'aplay'
Error: [Errno 2] No such file or directory: 'arecord'
Error: command ['fuser', '-v', '/dev/snd/seq', '/dev/snd/timer'] failed with exit code 1:
[Errno 2] No such file or directory: 'iwconfig'
Device 002: ID 8087:0020 Intel Corp. Integrated Rate Matching Hub
Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Device 003: ID 0424:2514 Microchip Technology, Inc. (formerly SMSC) USB 2.0 Hub
Device 002: ID 8087:0020 Intel Corp. Integrated Rate Matching Hub
Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
02.Port 1: Dev 1, Class=root_hub, Driver=ehci-pci/2p, 480M
Port 1: Dev 2, If 0, Class=Hub, Driver=hub/8p, 480M
01.Port 1: Dev 1, Class=root_hub, Driver=ehci-pci/2p, 480M
Port 1: Dev 2, If 0, Class=Hub, Driver=hub/6p, 480M
sp; |__ Port 1: Dev 3, If 0, Class=Hub, Driver=hub/4p, 480M
Dell Inc. PowerEdge R310

ProcEnviron:
TERM=xterm-256color
PATH=(custom, no user)
LANG=C.UTF-8
SHELL=/bin/bash
ProcFB: 0 mgag200drmfb
ProcKernelCmdLine: BOOT_IMAGE=/boot/vmlinuz-5.4.0-24-generic root=UUID=4f4b2733-f4eb-4b16-bdde-75e7132e3acc ro
ProcVersionSignature: Ubuntu 5.4.0-24.28-generic 5.4.30
RelatedPackageVersions:
linux-restricted-modules-5.4.0-24-generic N/A
linux-backports-modules-5.4.0-24-generic N/A
linux-firmware 1.187
RfKill: Error: [Errno 2] No such file or directory: 'rfkill'
Tags: focal uec-images
Uname: Linux 5.4.0-24-generic x86_64
UnreportableReason: This report is about a package that is not installed.
UpgradeStatus: No upgrade log present (probably fresh install)
UserGroups:

_MarkForUpload: False
dmi.bios.date: 09/06/2013
dmi.bios.vendor: Dell Inc.
dmi.bios.version: 1.12.0
dmi.board.name: 05XKKK
dmi.board.vendor: Dell Inc.
dmi.board.version: A09
dmi.chassis.type: 23
dmi.chassis.vendor: Dell Inc.
dmi.modalias: dmi:bvnDellInc.:bvr1.12.0:bd09/06/2013:svnDellInc.:pnPowerEdgeR310:pvr:rvnDellInc.:rn05XKKK:rvrA09:cvnDellInc.:ct23:cvr:
dmi.product.name: PowerEdge R310
dmi.sys.vendor: Dell Inc.

See original description

Tags:

Revision history for this message

Ubuntu Kernel Bot (ubuntu-kernel-bot) wrote on 2020-04-15: Missing required logs.

This bug is missing log files that will aid in diagnosing the problem. While running an Ubuntu kernel (not a mainline or third-party kernel) please enter the following command in a terminal window:

apport-collect 1873054

and then change the status of the bug to 'Confirmed'.

If, due to the nature of the issue you have encountered, you are unable to run this command, please add a comment stating that fact and change the bug status to 'Confirmed'.

This change has been made by an automated script, maintained by the Ubuntu Kernel Team.

Changed in linux (Ubuntu):
status:	New → Incomplete

Revision history for this message

Sean Feole (sfeole) wrote on 2020-04-15:

Also exhibited on ARM64: non-xgene systems.

We do not see these Ftrace Failures on AWS-Nitro backed ARM64 instances.

tags:

added: arm64

Revision history for this message

Sean Feole (sfeole) wrote on 2020-04-15: CRDA.txt

CRDA.txt Edit (468 bytes, text/plain)

apport information

tags:	added: apport-collected uec-images
description:	updated

Revision history for this message

Sean Feole (sfeole) wrote on 2020-04-15: CurrentDmesg.txt

CurrentDmesg.txt Edit (58.4 KiB, text/plain)

apport information

Revision history for this message

Sean Feole (sfeole) wrote on 2020-04-15: Lspci.txt

Lspci.txt Edit (45.4 KiB, text/plain)

apport information

Revision history for this message

Sean Feole (sfeole) wrote on 2020-04-15: Lspci-vt.txt

Lspci-vt.txt Edit (3.0 KiB, text/plain)

apport information

Revision history for this message

Sean Feole (sfeole) wrote on 2020-04-15: Lsusb-v.txt

Lsusb-v.txt Edit (12.5 KiB, text/plain)

apport information

Revision history for this message

Sean Feole (sfeole) wrote on 2020-04-15: ProcCpuinfoMinimal.txt

ProcCpuinfoMinimal.txt Edit (965 bytes, text/plain)

apport information

Revision history for this message

Sean Feole (sfeole) wrote on 2020-04-15: ProcInterrupts.txt

ProcInterrupts.txt Edit (6.5 KiB, text/plain)

apport information

Revision history for this message

Sean Feole (sfeole) wrote on 2020-04-15: ProcModules.txt

#10

ProcModules.txt Edit (2.8 KiB, text/plain)

apport information

Revision history for this message

Sean Feole (sfeole) wrote on 2020-04-15: UdevDb.txt

#11

UdevDb.txt Edit (129.9 KiB, text/plain)

apport information

Revision history for this message

Sean Feole (sfeole) wrote on 2020-04-15: WifiSyslog.txt

#12

WifiSyslog.txt Edit (65.0 KiB, text/plain)

apport information

Po-Hsu Lin (cypressyew) on 2020-04-16

tags:

added: ubuntu-kernel-selftests

Sean Feole (sfeole) on 2020-04-28

tags:

added: s390x

Revision history for this message

Po-Hsu Lin (cypressyew) wrote on 2020-05-18:

#13

For Focal amd64 it's failing on node rizzo with generic kernel but not the low-latency kernel.

tags:

added: sru-20200427

Revision history for this message

Po-Hsu Lin (cypressyew) wrote on 2020-06-08:

#14

This issue can be found on B-5.4-GCP as well
5.4.0-1011.11~18.04.1

Po-Hsu Lin (cypressyew) on 2020-06-09

tags:

added: sru-20200518

Revision history for this message

Po-Hsu Lin (cypressyew) wrote on 2020-08-26:

#15

Test passed on Focal 5.4.0-44.48 amd64 generic / lowlatency, arm64, s390x, P8 (not tested on P9, test aborted in other tests before this one)

Revision history for this message

Krzysztof Kozlowski (krzk) wrote on 2021-06-17:

#16

Found on groovy/azure 5.8.0-1034.36 (and previous 5.8.0-1033.35)

tags:

added: azure groovy sru-20210531

Revision history for this message

Krzysztof Kozlowski (krzk) wrote on 2021-06-17:

#17

Found on groovy/oracle 5.8.0-1032.33

tags:

added: 5.8 oracle

Revision history for this message

Krzysztof Kozlowski (krzk) wrote on 2021-06-21:

#18

Found on hirsute/azure 5.11.0-1007.7

tags:

added: 5.11 hirsute

Revision history for this message

Krzysztof Kozlowski (krzk) wrote on 2021-06-22 (last edit on 2021-06-22):

#19

Seems previous findings - hirsute/azure and groovy/oracle might not be correct as the "Test wakeup tracer" passes.

Report a bug

This report contains Public information

Everyone can see this information.

You are

Subscribing...

Edit bug mail

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.

ubuntu-kernel-tests

Test Wakeup Tracer / RT Tracer fails in ubuntu_kernel_selftests

Bug Description

Other bug subscribers

Bug attachments

Remote bug watches