Test Wakeup Tracer / RT Tracer fails in ubuntu_kernel_selftests

Bug #1873054 reported by Sean Feole on 2020-04-15
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
ubuntu-kernel-tests
Undecided
Unassigned
linux (Ubuntu)
Undecided
Unassigned
Focal
Undecided
Unassigned

Bug Description

In Focal,

The following tests fail on baremetal/amd64:

04/12 03:27:37 DEBUG| utils:0153| [stdout] # [51] Test wakeup tracer [FAIL]
04/12 03:27:39 DEBUG| utils:0153| [stdout] # [52] Test wakeup RT tracer [FAIL]
04/12 03:27:41 DEBUG| utils:0153| [stdout] # [53] event trigger - test inter-event histogram trigger expected fail actions [XFAIL]

Full output below.

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

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.

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
Sean Feole (sfeole) wrote :

Also exhibited on ARM64: non-xgene systems.

04/12 03:48:06 DEBUG| utils:0153| [stdout] # [50] Meta-selftest [PASS]
04/12 03:48:17 DEBUG| utils:0153| [stdout] # [51] Test wakeup tracer [FAIL]
04/12 03:48:40 DEBUG| utils:0153| [stdout] # [52] Test wakeup RT tracer [FAIL]
04/12 03:48:55 DEBUG| utils:0153| [stdout] # [53] event trigger - test inter-event histogram trigger expected fail actions [XFAIL]
04/12 03:49:16 DEBUG| utils:0153| [stdout] # [54] event trigger - test field variable support [PASS]

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

tags: added: arm64
Sean Feole (sfeole) wrote : CRDA.txt

apport information

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

apport information

Sean Feole (sfeole) wrote : Lspci.txt

apport information

apport information

apport information

apport information

apport information

apport information

apport information

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
Po-Hsu Lin (cypressyew) wrote :

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

tags: added: sru-20200427
Po-Hsu Lin (cypressyew) wrote :

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
Po-Hsu Lin (cypressyew) wrote :

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)

To post a comment you must log in.
This report contains Public information  Edit
Everyone can see this information.

Other bug subscribers