[PATCH] lib/core: Let ftrace know when the test started

[Dixit, Ashutosh]

On Wed, 18 Dec 2024 11:13:40 -0800, Kamil Konieczny wrote:
>
> Hi Dixit,,
> On 2024-12-17 at 15:19:59 -0800, Dixit, Ashutosh wrote:
> > On Tue, 17 Dec 2024 10:39:10 -0800, Umesh Nerlige Ramappa wrote:
> > >
> > > It's useful to know the start and end of the test in ftrace to be able
> > > to debug some issues. Not sure where to add igt_trace for end, so
> > > posting this patch to get some comments.
> >
> > Isn't it this (at least looking at dmesg):
> >
> > __noreturn static void exit_subtest(const char *result)
> > {
> >	...
> >	igt_kmsg(KMSG_INFO "%s: finished subtest %s, %s\n",
> >		 command_str, *subtest_name, result);
>
> I also think it is already printed in logs.
> Umesh if you find out cases where it is not please drop a link
> to CI log(s) to look into.
>
> Now that I look at it again the only diff is a place when it is
> printed, this one is after (dynamic) subtest ends but I still
> think it is also printed in dmesg before a dynamic subtest starts.
>
> For example look into this log:
> https://intel-gfx-ci.01.org/tree/drm-tip/IGTPW_12333/shard-snb5/igt@kms_flip@flip-vs-fences@a-vga1.html
>
> there is:
> <7> [377.462024] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:75:DP-1] disconnected
> <6> [377.463784] [IGT] kms_flip: starting subtest flip-vs-fences
> <6> [377.465812] [IGT] kms_flip: starting dynamic subtest A-VGA1

Umesh wants to print in ftrace not dmesg.