Public bug reported:
Issue found with O-oracle 6.11.0-1010.10 (s20241202) on BM.Optimized3.36
and BM.Standard3.64
Test passed on both instances with the 3rd attempt. Bug opened for
future reference.
Test failed with:
[1] event trigger - test inter-event histogram trigger snapshot action [FAIL]
Test log (partially truncated):
=== Ftrace unit tests ===
[1] event trigger - test inter-event histogram trigger snapshot action [FAIL]
execute:
/home/ubuntu/autotest/client/tmp/ubuntu_kselftests_ftrace/src/linux/tools/testing/selftests/ftrace/test.d/trigger/inter-event/trigger-snapshot-action-hist.tc
+ checkreq
/home/ubuntu/autotest/client/tmp/ubuntu_kselftests_ftrace/src/linux/tools/testing/selftests/ftrace/test.d/trigger/inter-event/trigger-snapshot-action-hist.tc
+ grep ^#[ \t]*requires:
/home/ubuntu/autotest/client/tmp/ubuntu_kselftests_ftrace/src/linux/tools/testing/selftests/ftrace/test.d/trigger/inter-event/trigger-snapshot-action-hist.tc
+ cut -f2- -d:
+ requires= set_event snapshot events/sched/sched_process_fork/hist
"onchange(var)":README "snapshot()":README ping:program
+ eval check_requires set_event snapshot events/sched/sched_process_fork/hist
"onchange(var)":README "snapshot()":README ping:program
+ check_requires set_event snapshot events/sched/sched_process_fork/hist
onchange(var):README snapshot():README ping:program
+ p=set_event
+ r=set_event
+ t=set_event
+ [ set_event != set_event ]
+ [ set_event != set_event ]
+ [ set_event != set_event ]
+ [ ! -e set_event ]
+ p=snapshot
+ r=snapshot
+ t=snapshot
+ [ snapshot != snapshot ]
+ [ snapshot != snapshot ]
+ [ snapshot != snapshot ]
+ [ ! -e snapshot ]
+ p=events/sched/sched_process_fork/hist
+ r=events/sched/sched_process_fork/hist
+ t=events/sched/sched_process_fork/hist
+ [ events/sched/sched_process_fork/hist !=
events/sched/sched_process_fork/hist ]
+ [ events/sched/sched_process_fork/hist !=
events/sched/sched_process_fork/hist ]
+ [ events/sched/sched_process_fork/hist !=
events/sched/sched_process_fork/hist ]
+ [ ! -e events/sched/sched_process_fork/hist ]
+ p=onchange(var):README
+ r=onchange(var)
+ t=onchange(var):README
+ [ onchange(var):README != onchange(var):README ]
+ [ onchange(var):README != onchange(var):README ]
+ [ onchange(var) != onchange(var):README ]
+ grep -Fq onchange(var) README
+ p=snapshot():README
+ r=snapshot()
+ t=snapshot():README
+ [ snapshot():README != snapshot():README ]
+ [ snapshot():README != snapshot():README ]
+ [ snapshot() != snapshot():README ]
+ grep -Fq snapshot() README
+ p=ping
+ r=ping:program
+ t=ping:program
+ [ ping != ping:program ]
+ which ping
/usr/bin/ping
+ initialize_ftrace
+ disable_tracing
+ echo 0
+ reset_tracer
+ echo nop
+ reset_trigger
+ [ -d events/synthetic ]
+ reset_trigger_file events/alarmtimer/alarmtimer_cancel/trigger
events/alarmtimer/alarmtimer_fired/trigger
events/alarmtimer/alarmtimer_start/trigger ...
+ read line
+ grep -H :on[^:]*( events/alarmtimer/alarmtimer_cancel/trigger
events/alarmtimer/alarmtimer_fired/trigger ...
+ read line
+ grep -Hv ^# events/alarmtimer/alarmtimer_cancel/trigger
events/alarmtimer/alarmtimer_fired/trigger ...
+ reset_events_filter
+ read line
+ grep -v ^none events/alarmtimer/alarmtimer_cancel/filter
events/alarmtimer/alarmtimer_fired/filter
events/alarmtimer/alarmtimer_start/filter ...
+ reset_ftrace_filter
+ [ ! -f set_ftrace_filter ]
+ echo
+ grep -v ^# set_ftrace_filter
+ read t
+ disable_events
+ echo 0
+ clear_dynamic_events
+ again=1
+ stop=1
+ [ 1 -eq 1 ]
+ stop=2
+ [ 2 -gt 10 ]
+ again=2
+ grep -v ^# dynamic_events
+ read line
+ [ 2 -eq 1 ]
+ [ -f set_event_pid ]
+ echo
+ [ -f set_ftrace_pid ]
+ echo
+ [ -f set_ftrace_notrace ]
+ echo
+ [ -f set_graph_function ]
+ echo
+ tee set_graph_function set_graph_notrace
+ [ -f stack_trace_filter ]
+ echo
+ [ -f kprobe_events ]
+ echo
+ [ -f uprobe_events ]
+ echo
+ [ -f synthetic_events ]
+ echo
+ [ -f snapshot ]
+ echo 0
+ [ -f options/pause-on-trace ]
+ echo 1
+ clear_trace
+ echo
+ enable_tracing
+ echo 1
+ .
/home/ubuntu/autotest/client/tmp/ubuntu_kselftests_ftrace/src/linux/tools/testing/selftests/ftrace/test.d/trigger/inter-event/trigger-snapshot-action-hist.tc
+ echo Test snapshot action
Test snapshot action
+ echo 1
+ echo
hist:keys=comm:newprio=prio:onchange($newprio).save(comm,prio):onchange($newprio).snapshot()
if comm=="ping"
+ ping 127.0.0.1 -c 3
PING 127.0.0.1 (127.0.0.1) 56(84) bytes of data.
64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.068 ms
64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.044 ms
64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.045 ms
--- 127.0.0.1 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2048ms
rtt min/avg/max/mdev = 0.044/0.052/0.068/0.011 ms
+ nice -n 1 ping 127.0.0.1 -c 3
PING 127.0.0.1 (127.0.0.1) 56(84) bytes of data.
64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.036 ms
64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.045 ms
64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.045 ms
--- 127.0.0.1 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2043ms
rtt min/avg/max/mdev = 0.036/0.042/0.045/0.004 ms
+ echo 0
+ grep -q changed: events/sched/sched_waking/hist
+ grep -q comm=ping snapshot
+ fail Failed to create snapshot action inter-event histogram
+ echo Failed to create snapshot action inter-event histogram
Failed to create snapshot action inter-event histogram
+ exit_fail
+ exit 1
** Affects: ubuntu-kernel-tests
Importance: Undecided
Status: New
** Tags: 6.11 oracle oracular s20241202
--
You received this bug notification because you are a member of Canonical
Platform QA Team, which is subscribed to ubuntu-kernel-tests.
https://bugs.launchpad.net/bugs/2095589
Title:
ubuntu_kselftests_ftrace/test.d--trigger--inter-event--trigger-
snapshot-action-hist.tc flaky on O-oracle
Status in ubuntu-kernel-tests:
New
Bug description:
Issue found with O-oracle 6.11.0-1010.10 (s20241202) on
BM.Optimized3.36 and BM.Standard3.64
Test passed on both instances with the 3rd attempt. Bug opened for
future reference.
Test failed with:
[1] event trigger - test inter-event histogram trigger snapshot action
[FAIL]
Test log (partially truncated):
=== Ftrace unit tests ===
[1] event trigger - test inter-event histogram trigger snapshot action
[FAIL]
execute:
/home/ubuntu/autotest/client/tmp/ubuntu_kselftests_ftrace/src/linux/tools/testing/selftests/ftrace/test.d/trigger/inter-event/trigger-snapshot-action-hist.tc
+ checkreq
/home/ubuntu/autotest/client/tmp/ubuntu_kselftests_ftrace/src/linux/tools/testing/selftests/ftrace/test.d/trigger/inter-event/trigger-snapshot-action-hist.tc
+ grep ^#[ \t]*requires:
/home/ubuntu/autotest/client/tmp/ubuntu_kselftests_ftrace/src/linux/tools/testing/selftests/ftrace/test.d/trigger/inter-event/trigger-snapshot-action-hist.tc
+ cut -f2- -d:
+ requires= set_event snapshot events/sched/sched_process_fork/hist
"onchange(var)":README "snapshot()":README ping:program
+ eval check_requires set_event snapshot
events/sched/sched_process_fork/hist "onchange(var)":README "snapshot()":README
ping:program
+ check_requires set_event snapshot events/sched/sched_process_fork/hist
onchange(var):README snapshot():README ping:program
+ p=set_event
+ r=set_event
+ t=set_event
+ [ set_event != set_event ]
+ [ set_event != set_event ]
+ [ set_event != set_event ]
+ [ ! -e set_event ]
+ p=snapshot
+ r=snapshot
+ t=snapshot
+ [ snapshot != snapshot ]
+ [ snapshot != snapshot ]
+ [ snapshot != snapshot ]
+ [ ! -e snapshot ]
+ p=events/sched/sched_process_fork/hist
+ r=events/sched/sched_process_fork/hist
+ t=events/sched/sched_process_fork/hist
+ [ events/sched/sched_process_fork/hist !=
events/sched/sched_process_fork/hist ]
+ [ events/sched/sched_process_fork/hist !=
events/sched/sched_process_fork/hist ]
+ [ events/sched/sched_process_fork/hist !=
events/sched/sched_process_fork/hist ]
+ [ ! -e events/sched/sched_process_fork/hist ]
+ p=onchange(var):README
+ r=onchange(var)
+ t=onchange(var):README
+ [ onchange(var):README != onchange(var):README ]
+ [ onchange(var):README != onchange(var):README ]
+ [ onchange(var) != onchange(var):README ]
+ grep -Fq onchange(var) README
+ p=snapshot():README
+ r=snapshot()
+ t=snapshot():README
+ [ snapshot():README != snapshot():README ]
+ [ snapshot():README != snapshot():README ]
+ [ snapshot() != snapshot():README ]
+ grep -Fq snapshot() README
+ p=ping
+ r=ping:program
+ t=ping:program
+ [ ping != ping:program ]
+ which ping
/usr/bin/ping
+ initialize_ftrace
+ disable_tracing
+ echo 0
+ reset_tracer
+ echo nop
+ reset_trigger
+ [ -d events/synthetic ]
+ reset_trigger_file events/alarmtimer/alarmtimer_cancel/trigger
events/alarmtimer/alarmtimer_fired/trigger
events/alarmtimer/alarmtimer_start/trigger ...
+ read line
+ grep -H :on[^:]*( events/alarmtimer/alarmtimer_cancel/trigger
events/alarmtimer/alarmtimer_fired/trigger ...
+ read line
+ grep -Hv ^# events/alarmtimer/alarmtimer_cancel/trigger
events/alarmtimer/alarmtimer_fired/trigger ...
+ reset_events_filter
+ read line
+ grep -v ^none events/alarmtimer/alarmtimer_cancel/filter
events/alarmtimer/alarmtimer_fired/filter
events/alarmtimer/alarmtimer_start/filter ...
+ reset_ftrace_filter
+ [ ! -f set_ftrace_filter ]
+ echo
+ grep -v ^# set_ftrace_filter
+ read t
+ disable_events
+ echo 0
+ clear_dynamic_events
+ again=1
+ stop=1
+ [ 1 -eq 1 ]
+ stop=2
+ [ 2 -gt 10 ]
+ again=2
+ grep -v ^# dynamic_events
+ read line
+ [ 2 -eq 1 ]
+ [ -f set_event_pid ]
+ echo
+ [ -f set_ftrace_pid ]
+ echo
+ [ -f set_ftrace_notrace ]
+ echo
+ [ -f set_graph_function ]
+ echo
+ tee set_graph_function set_graph_notrace
+ [ -f stack_trace_filter ]
+ echo
+ [ -f kprobe_events ]
+ echo
+ [ -f uprobe_events ]
+ echo
+ [ -f synthetic_events ]
+ echo
+ [ -f snapshot ]
+ echo 0
+ [ -f options/pause-on-trace ]
+ echo 1
+ clear_trace
+ echo
+ enable_tracing
+ echo 1
+ .
/home/ubuntu/autotest/client/tmp/ubuntu_kselftests_ftrace/src/linux/tools/testing/selftests/ftrace/test.d/trigger/inter-event/trigger-snapshot-action-hist.tc
+ echo Test snapshot action
Test snapshot action
+ echo 1
+ echo
hist:keys=comm:newprio=prio:onchange($newprio).save(comm,prio):onchange($newprio).snapshot()
if comm=="ping"
+ ping 127.0.0.1 -c 3
PING 127.0.0.1 (127.0.0.1) 56(84) bytes of data.
64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.068 ms
64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.044 ms
64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.045 ms
--- 127.0.0.1 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2048ms
rtt min/avg/max/mdev = 0.044/0.052/0.068/0.011 ms
+ nice -n 1 ping 127.0.0.1 -c 3
PING 127.0.0.1 (127.0.0.1) 56(84) bytes of data.
64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.036 ms
64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.045 ms
64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.045 ms
--- 127.0.0.1 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2043ms
rtt min/avg/max/mdev = 0.036/0.042/0.045/0.004 ms
+ echo 0
+ grep -q changed: events/sched/sched_waking/hist
+ grep -q comm=ping snapshot
+ fail Failed to create snapshot action inter-event histogram
+ echo Failed to create snapshot action inter-event histogram
Failed to create snapshot action inter-event histogram
+ exit_fail
+ exit 1
To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu-kernel-tests/+bug/2095589/+subscriptions
--
Mailing list: https://launchpad.net/~canonical-ubuntu-qa
Post to : canonical-ubuntu-qa@lists.launchpad.net
Unsubscribe : https://launchpad.net/~canonical-ubuntu-qa
More help : https://help.launchpad.net/ListHelp
