[Bug testsuite/27667] New: FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[Bug testsuite/27667] New: FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

            Bug ID: 27667
           Summary: FAIL: gdb.mi/mi-sym-info.exp: List all functions from
                    debug information only (timeout)
           Product: gdb
           Version: HEAD
            Status: NEW
          Severity: normal
          Priority: P2
         Component: testsuite
          Assignee: unassigned at sourceware dot org
          Reporter: vries at gcc dot gnu.org
  Target Milestone: ---

I've started to run into this timeout, reproducibly:
...
Running src/gdb/testsuite/gdb.mi/mi-sym-info.exp ...
FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only
(timeout)
FAIL: gdb.mi/mi-sym-info.exp: List all variables from debug information only
ERROR: internal buffer is full.
FAIL: gdb.mi/mi-sym-info.exp: List all functions
FAIL: gdb.mi/mi-sym-info.exp: List all variables (timeout)
FAIL: gdb.mi/mi-sym-info.exp: List all variables (timeout)
DUPLICATE: gdb.mi/mi-sym-info.exp: List all variables (timeout)
FAIL: gdb.mi/mi-sym-info.exp: List all functions matching pattern f3 (timeout)
FAIL: gdb.mi/mi-sym-info.exp: List all functions matching type void (timeout)
FAIL: gdb.mi/mi-sym-info.exp: List all variables matching pattern global_f2
(timeout)
FAIL: gdb.mi/mi-sym-info.exp: List all variables matching type float (timeout)
FAIL: gdb.mi/mi-sym-info.exp: List all types matching _int_ (timeout)
FAIL: gdb.mi/mi-sym-info.exp: -symbol-info-functions --max-results 0 (timeout)
FAIL: gdb.mi/mi-sym-info.exp: -symbol-info-functions --max-results 1 (timeout)
FAIL: gdb.mi/mi-sym-info.exp: -symbol-info-functions --max-results 2 (timeout)
FAIL: gdb.mi/mi-sym-info.exp: -symbol-info-types --max-results 4 (timeout)
...

Reproducible using:
...
$ time ./gdb.sh -batch -q outputs/gdb.mi/mi-sym-info/mi-sym-info -ex start -ex
"interpreter-exec mi 111-symbol-info-functions"

Temporary breakpoint 1 at 0x4004dd: file mi-sym-info-1.c, line 44.

Temporary breakpoint 1, main () at mi-sym-info-1.c:44
44        int v = f3 (4);
111^done,symbols={debug=[{filename="../../../libgcc/config/i386/sfp-exceptions.c",fullname="/<SNIP>
real    0m21.710s
user    0m6.492s
sys     0m0.199s

$
...

The most time is spent waiting for the first command output to appear.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #1 from Tom de Vries <vries at gcc dot gnu.org> ---
Reproduces with gdb 10.2 branch.

After running the test-case we have:
...
$ wc gdb.log 
    217   43949 1053928 gdb.log
...

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #2 from Tom de Vries <vries at gcc dot gnu.org> ---
Hmm, at gdb-9-branch I have instead:
...
real    0m4.659s
user    0m4.493s
sys     0m0.134s
...

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #3 from Tom de Vries <vries at gcc dot gnu.org> ---
This bisects to commit 0d79cdc494d "Add debuginfod support to GDB".

At commit:
...
$ time ./gdb.sh -batch -q ./mi-sym-info -ex start -ex "interpreter-exec mi
111-symbol-info-functions" >/dev/null; echo $?
warning: Source file is more recent than executable.

real    0m18.203s
user    0m5.050s
sys     0m0.127s
0
...

Before commit:
...
$ time ./gdb.sh -batch -q ./mi-sym-info -ex start -ex "interpreter-exec mi
111-symbol-info-functions" >/dev/null; echo $?
warning: Source file is more recent than executable.

real    0m4.607s
user    0m4.515s
sys     0m0.121s
0
...


Hmm, indeed I seem to have:
...
$ echo $DEBUGINFOD_URLS
https://debuginfod.opensuse.org/
...

I was only expecting this on tumbleweed, not on my leap 15.2 installation, but
my elfutils is from the Base:System development project:
...
$ zypper info elfutils

Information for package elfutils:
---------------------------------
Repository     : Base:System
Name           : elfutils
Version        : 0.183-lp152.189.1
Arch           : x86_64
Vendor         : obs://build.opensuse.org/Base:System
Installed Size : 2.6 MiB
Installed      : Yes
Status         : up-to-date
Source package : elfutils-0.183-lp152.189.1.src
Summary        : Higher-level library to access ELF files
Description    : 
    elfutils is a collection of utilities and libraries to read, create
    and modify ELF binary files, find and handle DWARF debug data,
    symbols, thread state and stacktraces for processes and core files.
...
which explains how I got that setting.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #4 from Tom de Vries <vries at gcc dot gnu.org> ---
Another comparison, now with textual output also measured:
...
$ time ./gdb.sh -batch -q ./mi-sym-info -ex start -ex "interpreter-exec mi
111-symbol-info-functions" >OUTPUT; echo $?; wc OUTPUT
warning: Source file is more recent than executable.

real    0m4.601s
user    0m4.537s
sys     0m0.094s
0
     5  32727 752273 OUTPUT
$ time ./gdb.sh -batch -q ./mi-sym-info -ex start -ex "interpreter-exec mi
111-symbol-info-functions" >OUTPUT; echo $?; wc OUTPUT
warning: Source file is more recent than executable.

real    0m18.923s
user    0m5.042s
sys     0m0.171s
0
     5  32727 752273 OUTPUT
$
...

Hmm, it's the same output, so it's also not that we print more information due
to
DEBUGINFOD_URLS.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[matz at suse dot de]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

Michael Matz <matz at suse dot de> changed:

           What    |Removed                     |Added
----------------------------------------------------------------------------
                 CC|                            |matz at suse dot de

--- Comment #5 from Michael Matz <matz at suse dot de> ---
The timing would indicate to me that it tries to connect to the server and
ultimately that returns failure (or is just slow to report failure).  I wonder
for which parts of the executable it does that?  And why does the second run
also take that much time (I'm not sure about caching, and how it's evicted).

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[matz at suse dot de]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #6 from Michael Matz <matz at suse dot de> ---
(possibly a good work around would be to start with resetting DEBUGINFOD_SERVER
from the environment in the gdb testsuite infrastructure.  I'm predicting that
more distros will eventually start setting that facility by default.)

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #7 from Tom de Vries <vries at gcc dot gnu.org> ---
Using some debug printing:
...
diff --git a/gdb/debuginfod-support.c b/gdb/debuginfod-support.c
index e0f0fac0766..d3672700dc3 100644
--- a/gdb/debuginfod-support.c
+++ b/gdb/debuginfod-support.c
@@ -98,6 +98,8 @@ debuginfod_source_query (const unsigned char *build_id,
   if (c == nullptr)
     return scoped_fd (-ENOMEM);

+  fprintf (stderr, "TRYING SOURCE: %s\n", srcpath);
+
   desc = std::string ("source file");
   fname = std::string (srcpath);
   has_printed = false;
@@ -136,6 +138,8 @@ debuginfod_debuginfo_query (const unsigned char *build_id,
   if (c == nullptr)
     return scoped_fd (-ENOMEM);

+  fprintf (stderr, "TRYING DEBUGINFO FOR: %s\n", filename);
+
   desc = std::string ("separate debug info for");
   fname = std::string (filename);
   has_printed = false;
...
we get:
...
$ ./gdb.sh -batch -q ./mi-sym-info -ex start -ex "interpreter-exec mi
111-symbol-info-functions" > OUTPUT
TRYING DEBUGINFO FOR: /home/vries/gdb_versions/devel/system-supplied DSO at
0x7ffff7ffa000
warning: Source file is more recent than executable.
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/config/i386/sfp-exceptions.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/addtf3.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/divtf3.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/eqtf2.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/extenddftf2.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/fixtfdi.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/fixtfsi.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/floatditf.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/floatsitf.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/floatunsitf.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/getf2.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/letf2.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/multf3.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/subtf3.c
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/unordtf2.c
TRYING SOURCE: /home/abuild/rpmbuild/BUILD/glibc-2.26/csu/elf-init.c
...

We do for some reason try to get libgcc2.c several times.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[simark at simark dot ca]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

Simon Marchi <simark at simark dot ca> changed:

           What    |Removed                     |Added
----------------------------------------------------------------------------
                 CC|                            |simark at simark dot ca

--- Comment #8 from Simon Marchi <simark at simark dot ca> ---
(In reply to Tom de Vries from comment #7)
> Using some debug printing:
> ...
> diff --git a/gdb/debuginfod-support.c b/gdb/debuginfod-support.c
> index e0f0fac0766..d3672700dc3 100644
> --- a/gdb/debuginfod-support.c
> +++ b/gdb/debuginfod-support.c
> @@ -98,6 +98,8 @@ debuginfod_source_query (const unsigned char *build_id,
>    if (c == nullptr)
>      return scoped_fd (-ENOMEM);
>  
> +  fprintf (stderr, "TRYING SOURCE: %s\n", srcpath);
> +
>    desc = std::string ("source file");
>    fname = std::string (srcpath);
>    has_printed = false;
> @@ -136,6 +138,8 @@ debuginfod_debuginfo_query (const unsigned char
> *build_id,
>    if (c == nullptr)
>      return scoped_fd (-ENOMEM);
>  
> +  fprintf (stderr, "TRYING DEBUGINFO FOR: %s\n", filename);
> +
>    desc = std::string ("separate debug info for");
>    fname = std::string (filename);
>    has_printed = false;

Hi Tom!

If you found these debug prints useful, chances are they'll be useful to others
too in the future, so please consider adding them upstream :)

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #9 from Tom de Vries <vries at gcc dot gnu.org> ---
(In reply to Simon Marchi from comment #8)
> Hi Tom!
> 
> If you found these debug prints useful, chances are they'll be useful to
> others too in the future, so please consider adding them upstream :)

Hi Simon.

That makes sense, I'll see what I can do.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #10 from Tom de Vries <vries at gcc dot gnu.org> ---
Using this patch we get query result (-2 means -ENOENT) and wall clock time:
...
diff --git a/gdb/debuginfod-support.c b/gdb/debuginfod-support.c
index e0f0fac0766..5c7acd41e55 100644
--- a/gdb/debuginfod-support.c
+++ b/gdb/debuginfod-support.c
@@ -21,6 +21,7 @@
 #include "cli/cli-style.h"
 #include "gdbsupport/scoped_fd.h"
 #include "debuginfod-support.h"
+#include <chrono>

 #ifndef HAVE_LIBDEBUGINFOD
 scoped_fd
@@ -98,15 +99,23 @@ debuginfod_source_query (const unsigned char *build_id,
   if (c == nullptr)
     return scoped_fd (-ENOMEM);

+  fprintf (stderr, "TRYING SOURCE: %s\n", srcpath);
+
   desc = std::string ("source file");
   fname = std::string (srcpath);
   has_printed = false;

+  auto start = std::chrono::high_resolution_clock::now ();
   scoped_fd fd (debuginfod_find_source (c,
                                        build_id,
                                        build_id_len,
                                        srcpath,
                                        nullptr));
+  auto end = std::chrono::high_resolution_clock::now ();
+
+  fprintf (stderr, "RESULT fd: %d\n", fd.get ());
+  std::chrono::duration<double> diff = end-start;
+  fprintf (stderr, "TOOK: %f seconds\n", diff.count ());

   /* TODO: Add 'set debug debuginfod' command to control when error messages
are shown.  */
   if (fd.get () < 0 && fd.get () != -ENOENT)
@@ -136,12 +145,20 @@ debuginfod_debuginfo_query (const unsigned char
*build_id,
   if (c == nullptr)
     return scoped_fd (-ENOMEM);

+  fprintf (stderr, "TRYING DEBUGINFO FOR: %s\n", filename);
+
   desc = std::string ("separate debug info for");
   fname = std::string (filename);
   has_printed = false;
   char *dname = nullptr;

+  auto start = std::chrono::high_resolution_clock::now();
   scoped_fd fd (debuginfod_find_debuginfo (c, build_id, build_id_len,
&dname));
+  auto end = std::chrono::high_resolution_clock::now();
+
+  fprintf (stderr, "RESULT fd: %d\n", fd.get ());
+  std::chrono::duration<double> diff = end-start;
+  fprintf (stderr, "TOOK: %f seconds\n", diff.count ());

   if (fd.get () < 0 && fd.get () != -ENOENT)
     printf_filtered (_("Download failed: %s.  Continuing without debug info
for %ps.\n"),
...

So let's try once more:
...
$ ./gdb.sh -batch -q ./mi-sym-info -ex start -ex "interpreter-exec mi
111-symbol-info-functions" > OUTPUT
TRYING DEBUGINFO FOR: /home/vries/gdb_versions/devel/system-supplied DSO at
0x7ffff7ffa000
RESULT fd: -2
TOOK: 0.726428 seconds
warning: Source file is more recent than executable.
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/config/i386/sfp-exceptions.c
RESULT fd: -2
TOOK: 0.765119 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.613696 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.784055 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.586914 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.745929 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.607132 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/addtf3.c
RESULT fd: -2
TOOK: 0.560357 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/divtf3.c
RESULT fd: -2
TOOK: 0.869566 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/eqtf2.c
RESULT fd: -2
TOOK: 0.612887 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/extenddftf2.c
RESULT fd: -2
TOOK: 0.562272 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/fixtfdi.c
RESULT fd: -2
TOOK: 0.763371 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/fixtfsi.c
RESULT fd: -2
TOOK: 0.610106 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/floatditf.c
RESULT fd: -2
TOOK: 0.611289 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/floatsitf.c
RESULT fd: -2
TOOK: 0.612558 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/floatunsitf.c
RESULT fd: -2
TOOK: 0.614803 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/getf2.c
RESULT fd: -2
TOOK: 0.601872 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/letf2.c
RESULT fd: -2
TOOK: 0.615150 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/multf3.c
RESULT fd: -2
TOOK: 0.613128 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/subtf3.c
RESULT fd: -2
TOOK: 0.921333 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/unordtf2.c
RESULT fd: -2
TOOK: 0.613586 seconds
TRYING SOURCE: /home/abuild/rpmbuild/BUILD/glibc-2.26/csu/elf-init.c
RESULT fd: -2
TOOK: 0.756171 seconds
...

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[matz at suse dot de]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #11 from Michael Matz <matz at suse dot de> ---
TRYING DEBUGINFO FOR: /home/vries/gdb_versions/devel/system-supplied DSO at
0x7ffff7ffa000
RESULT fd: -2
TOOK: 0.726428 seconds

So that's strange in itself already.  It's the vDSO of the kernel (and it might
contain a build-id), but why it should look for that by prepending a directory
name ...

warning: Source file is more recent than executable.
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-7.5.0+r278197/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/config/i386/sfp-exceptions.c
RESULT fd: -2
TOOK: 0.765119 seconds

In any case: it seems it's not only trying to fetch debuginfo files but also
source files.  Aren't our debuginfod servers only serving the former?  Even if
they serve debugsource files: shouldn't they rather serve archives of such
files,
instead of individually, in which case a negative result could be cached
quicker?

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #12 from Tom de Vries <vries at gcc dot gnu.org> ---
One more data point: Tumbleweed:
...
$ ./gdb.sh -batch -q ./mi-sym-info -ex start -ex "interpreter-exec mi
111-symbol-info-functions" > OUTPUT
TRYING DEBUGINFO FOR: /data/gdb_versions/devel/system-supplied DSO at
0x7ffff7fca000
RESULT fd: 16
TOOK: 0.000089 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/config/i386/sfp-exceptions.c
RESULT fd: -2
TOOK: 0.623930 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.613617 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.601827 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.602272 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.602107 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.599082 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/addtf3.c
RESULT fd: -2
TOOK: 0.607089 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/divtf3.c
RESULT fd: -2
TOOK: 0.613741 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/eqtf2.c
RESULT fd: -2
TOOK: 0.613553 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/extenddftf2.c
RESULT fd: -2
TOOK: 0.606222 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/fixtfdi.c
RESULT fd: -2
TOOK: 0.613666 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/fixtfsi.c
RESULT fd: -2
TOOK: 0.607669 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/floatditf.c
RESULT fd: -2
TOOK: 0.607873 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/floatsitf.c
RESULT fd: -2
TOOK: 0.613491 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/floatunsitf.c
RESULT fd: -2
TOOK: 0.613899 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/getf2.c
RESULT fd: -2
TOOK: 0.608156 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/letf2.c
RESULT fd: -2
TOOK: 0.613248 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/multf3.c
RESULT fd: -2
TOOK: 0.613727 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/subtf3.c
RESULT fd: -2
TOOK: 0.613447 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/trunctfdf2.c
RESULT fd: -2
TOOK: 0.613533 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/trunctfsf2.c
RESULT fd: -2
TOOK: 0.618561 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/trunctfxf2.c
RESULT fd: -2
TOOK: 0.609099 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/unordtf2.c
RESULT fd: -2
TOOK: 0.613974 seconds
TRYING SOURCE:
/usr/src/debug/kernel-default-5.11.6-1.4.x86_64/linux-5.11/linux-obj/../arch/x86/entry/vdso/../../../../lib/vdso/gettimeofday.c
RESULT fd: 17
TOOK: 0.000148 seconds
TRYING SOURCE:
/usr/src/debug/kernel-default-5.11.6-1.4.x86_64/linux-5.11/linux-obj/../arch/x86/entry/vdso/vclock_gettime.c
RESULT fd: 17
TOOK: 0.000159 seconds
TRYING SOURCE:
/usr/src/debug/kernel-default-5.11.6-1.4.x86_64/linux-5.11/linux-obj/../arch/x86/entry/vdso/vgetcpu.c
RESULT fd: 17
TOOK: 0.000155 seconds
TRYING SOURCE:
/usr/src/debug/kernel-default-5.11.6-1.4.x86_64/linux-5.11/linux-obj/../arch/x86/include/asm/vdso/gettimeofday.h
RESULT fd: 17
TOOK: 0.000173 seconds
TRYING SOURCE:
/usr/src/debug/glibc-2.33-4.1.x86_64/io/../sysdeps/unix/sysv/linux/wordsize-64/../fstatvfs.c
RESULT fd: 17
TOOK: 0.000111 seconds
TRYING SOURCE:
/usr/src/debug/glibc-2.33-4.1.x86_64/io/../sysdeps/unix/sysv/linux/wordsize-64/../statvfs.c
RESULT fd: 17
TOOK: 0.000063 seconds
TRYING SOURCE: /home/abuild/rpmbuild/BUILD/glibc-2.33/csu/elf-init.c
RESULT fd: -2
TOOK: 0.758786 seconds
vries@lena:/data/gdb_versions/devel> time ./gdb.sh -batch -q ./mi-sym-info -ex
start -ex "interpreter-exec mi 111-symbol-info-functions" > OUTPUT
TRYING DEBUGINFO FOR: /data/gdb_versions/devel/system-supplied DSO at
0x7ffff7fca000
RESULT fd: 16
TOOK: 0.000083 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/config/i386/sfp-exceptions.c
RESULT fd: -2
TOOK: 0.569078 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.716225 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.602872 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.600775 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.605775 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
RESULT fd: -2
TOOK: 0.599927 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/addtf3.c
RESULT fd: -2
TOOK: 0.607896 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/divtf3.c
RESULT fd: -2
TOOK: 0.613691 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/eqtf2.c
RESULT fd: -2
TOOK: 0.613604 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/extenddftf2.c
RESULT fd: -2
TOOK: 0.606232 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/fixtfdi.c
RESULT fd: -2
TOOK: 0.613668 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/fixtfsi.c
RESULT fd: -2
TOOK: 0.608370 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/floatditf.c
RESULT fd: -2
TOOK: 0.606956 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/floatsitf.c
RESULT fd: -2
TOOK: 0.715559 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/floatunsitf.c
RESULT fd: -2
TOOK: 0.613503 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/getf2.c
RESULT fd: -2
TOOK: 0.606873 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/letf2.c
RESULT fd: -2
TOOK: 0.613583 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/multf3.c
RESULT fd: -2
TOOK: 0.613548 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/subtf3.c
RESULT fd: -2
TOOK: 0.613803 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/trunctfdf2.c
RESULT fd: -2
TOOK: 0.613427 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/trunctfsf2.c
RESULT fd: -2
TOOK: 0.613815 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/trunctfxf2.c
RESULT fd: -2
TOOK: 0.613366 seconds
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/soft-fp/unordtf2.c
RESULT fd: -2
TOOK: 0.614070 seconds
TRYING SOURCE:
/usr/src/debug/kernel-default-5.11.6-1.4.x86_64/linux-5.11/linux-obj/../arch/x86/entry/vdso/../../../../lib/vdso/gettimeofday.c
RESULT fd: 17
TOOK: 0.000147 seconds
TRYING SOURCE:
/usr/src/debug/kernel-default-5.11.6-1.4.x86_64/linux-5.11/linux-obj/../arch/x86/entry/vdso/vclock_gettime.c
RESULT fd: 17
TOOK: 0.000213 seconds
TRYING SOURCE:
/usr/src/debug/kernel-default-5.11.6-1.4.x86_64/linux-5.11/linux-obj/../arch/x86/entry/vdso/vgetcpu.c
RESULT fd: 17
TOOK: 0.000159 seconds
TRYING SOURCE:
/usr/src/debug/kernel-default-5.11.6-1.4.x86_64/linux-5.11/linux-obj/../arch/x86/include/asm/vdso/gettimeofday.h
RESULT fd: 17
TOOK: 0.000164 seconds
TRYING SOURCE:
/usr/src/debug/glibc-2.33-4.1.x86_64/io/../sysdeps/unix/sysv/linux/wordsize-64/../fstatvfs.c
RESULT fd: 17
TOOK: 0.000070 seconds
TRYING SOURCE:
/usr/src/debug/glibc-2.33-4.1.x86_64/io/../sysdeps/unix/sysv/linux/wordsize-64/../statvfs.c
RESULT fd: 17
TOOK: 0.000054 seconds
TRYING SOURCE: /home/abuild/rpmbuild/BUILD/glibc-2.33/csu/elf-init.c
RESULT fd: -2
TOOK: 0.535912 seconds
...

It's interesting that the files that are found (fd >= 0) take very little time.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #13 from Tom de Vries <vries at gcc dot gnu.org> ---
(In reply to Tom de Vries from comment #12)
> It's interesting that the files that are found (fd >= 0) take very little
> time.

Ah, that must be the caching in ~/.cache/debuginfod_client/ . And there's no
negative caching yet, see PR25628.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[mliska at suse dot cz]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

Martin Liska <mliska at suse dot cz> changed:

           What    |Removed                     |Added
----------------------------------------------------------------------------
                 CC|                            |mliska at suse dot cz

--- Comment #14 from Martin Liska <mliska at suse dot cz> ---
Unfortunately our debuginfo server is in Provo (Utah) and so we get:

$ ping provo-mirror.opensuse.org
PING provo-mirror.opensuse.org (91.193.113.70) 56(84) bytes of data.
64 bytes from provo-mirror.opensuse.org (91.193.113.70): icmp_seq=1 ttl=246
time=162 ms

and thus we have round trip time around 500ms:

$ curl -o /dev/null -s -w %{time_total}\\n
https://debuginfod.opensuse.org/buildid/123/debuginfo
0.516211

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #15 from Tom de Vries <vries at gcc dot gnu.org> ---
I've added printing of buildid as well:
...
TRYING SOURCE:
/home/abuild/rpmbuild/BUILD/gcc-10.2.1+git1331/obj-x86_64-suse-linux/x86_64-suse-linux/libgcc/../../../libgcc/libgcc2.c
BUILDID: d34928e1b5c7bde5e269e47349ade7369a3efa83
RESULT fd: -2
...

So this is libm:
...
$ file  /usr/lib/debug/.build-id/d3/4928e1b5c7bde5e269e47349ade7369a3efa83
/usr/lib/debug/.build-id/d3/4928e1b5c7bde5e269e47349ade7369a3efa83: symbolic
link to ../../../../../lib64/libm-2.33.so
...
coming from package glibc, for which I have debuginfo and debugsources
installed.

However, the file name comes from statically linked libgcc.a, so the source is
not included in that package.

I actually also have the gcc10-debugsource package installed which contains
precisely the file and version we're looking for:
...
$ find /usr/src/debug/gcc10-10.2.1+git1331-1.2.x86_64/ -name libgcc2.c
/usr/src/debug/gcc10-10.2.1+git1331-1.2.x86_64/libgcc/libgcc2.c
...

After much trial and error, I finally found a command line invocation that
finds the file in question:
...
$ debuginfod-find source
/usr/lib/debug/usr/lib64/gcc/x86_64-suse-linux/10/f951-10.2.1+git1331-1.2.x86_64.debug
/usr/src/debug/gcc10-10.2.1+git1331-1.2.x86_64/libgcc/libgcc2.c
/home/vries/.cache/debuginfod_client/bd0d7673ca09c0fbd82cb42cb43bfef6f851d9ec/source##usr##src##debug##gcc10-10.2.1+git1331-1.2.x86_64##libgcc##libgcc2.c
...

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #16 from Tom de Vries <vries at gcc dot gnu.org> ---
It's good to note that looking for the source files is specific to the mi
command.  When using the non-mi variant "info functions" instead, we have:
...
$ time ./gdb.sh -batch -q ./mi-sym-info -ex start -ex "info functions" > OUTPUT
TRYING DEBUGINFO FOR: /data/gdb_versions/devel/system-supplied DSO at
0x7ffff7fca000
BUILDID: e77a560007595d823e2c5a34697c0b0ae1ffc767
RESULT fd: 16
TOOK: 0.000136 seconds

real    0m7.074s
user    0m6.989s
sys     0m0.138s
...

The mi command symbol-info-function insist on finding the fullname for a file,
which it does by trying to open the file, which causes all the source lookups.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #17 from Tom de Vries <vries at gcc dot gnu.org> ---
We could do a hack in the debuginfod client that if
https://debuginfod.opensuse.org/ is queried and the file doesn't start with
/usr/src/debug, we just return ENOENT.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #18 from Tom de Vries <vries at gcc dot gnu.org> ---
(In reply to Michael Matz from comment #5)
> And why does the second run
> also take that much time

The first run is before the commit adding debuginfod support, the second run is
at the commit adding debuginfod support.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #19 from Tom de Vries <vries at gcc dot gnu.org> ---
(In reply to Michael Matz from comment #6)
> (possibly a good work around would be to start with resetting
> DEBUGINFOD_SERVER
> from the environment in the gdb testsuite infrastructure.  I'm predicting
> that
> more distros will eventually start setting that facility by default.)

Agreed, I'll submit a patch to that effect.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #20 from Tom de Vries <vries at gcc dot gnu.org> ---
(In reply to Michael Matz from comment #11)
> shouldn't they rather serve archives of
> such files,
> instead of individually, in which case a negative result could be cached
> quicker?

Agreed, serving source archives should be quicker.

Also, possibly as intermediate solution the server could support a query that
returns the list of files, and possibly even export that functionality in the
api.

I'll file a PR for debuginfod.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #21 from Tom de Vries <vries at gcc dot gnu.org> ---
(In reply to Tom de Vries from comment #19)
> (In reply to Michael Matz from comment #6)
> > (possibly a good work around would be to start with resetting
> > DEBUGINFOD_SERVER
> > from the environment in the gdb testsuite infrastructure.  I'm predicting
> > that
> > more distros will eventually start setting that facility by default.)
> 
> Agreed, I'll submit a patch to that effect.

https://sourceware.org/pipermail/gdb-patches/2021-March/177392.html

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[cvs-commit at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #22 from cvs-commit at gcc dot gnu.org <cvs-commit at gcc dot gnu.org> ---
The master branch has been updated by Tom de Vries <vries@sourceware.org>:

https://sourceware.org/git/gitweb.cgi?p=binutils-gdb.git;h=cfcbd506fb0262070f58d089bf58502d8f677dd5

commit cfcbd506fb0262070f58d089bf58502d8f677dd5
Author: Tom de Vries <tdevries@suse.de>
Date:   Wed Mar 31 15:17:19 2021 +0200

    [gdb/testsuite] Ignore DEBUGINFOD_URLS

    On openSUSE Tumbleweed, DEBUGINFOD_URLS is now defined by default:
    ...
    $ echo $DEBUGINFOD_URLS
    https://debuginfod.opensuse.org/
    ...

    With DEBUGINFOD_URLS defined we run into:
    ...
    FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information
only \
      (timeout)
    ...
    as reported in PR27667.

    There's a latency of ~0.5s per request, which is ok-ish for interactive
usage.
    But the symbol-info-functions command ends up issuing 21 source requests,
    which means we easily run into the 10s timeout.

    Fix this by unsetting DEBUGINFOD_URLS in default_gdb_init.

    gdb/testsuite/ChangeLog:

    2021-03-31  Tom de Vries  <tdevries@suse.de>

            PR testsuite/27667
            * lib/gdb.exp (default_gdb_init): Unset DEBUGINFOD_URLS.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #23 from Tom de Vries <vries at gcc dot gnu.org> ---
(In reply to Tom de Vries from comment #9)
> (In reply to Simon Marchi from comment #8)
> > Hi Tom!
> > 
> > If you found these debug prints useful, chances are they'll be useful to
> > others too in the future, so please consider adding them upstream :)
> 
> Hi Simon.
> 
> That makes sense, I'll see what I can do.

Posted https://sourceware.org/pipermail/gdb-patches/2021-March/177397.html

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #24 from Tom de Vries <vries at gcc dot gnu.org> ---
(In reply to Michael Matz from comment #11)
> TRYING DEBUGINFO FOR: /home/vries/gdb_versions/devel/system-supplied DSO at
> 0x7ffff7ffa000
> RESULT fd: -2
> TOOK: 0.726428 seconds
> 
> So that's strange in itself already.  It's the vDSO of the kernel (and it
> might contain a build-id), but why it should look for that by prepending a
> directory name ...

The lookup is done by buildid, the file name is just FYI.  Anyway, AFAICT, the
prepending of the directory name is done by bfd.

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #25 from Tom de Vries <vries at gcc dot gnu.org> ---
(In reply to Tom de Vries from comment #20)
> (In reply to Michael Matz from comment #11)
> > shouldn't they rather serve archives of
> > such files,
> > instead of individually, in which case a negative result could be cached
> > quicker?
> 
> Agreed, serving source archives should be quicker.
> 
> Also, possibly as intermediate solution the server could support a query
> that returns the list of files, and possibly even export that functionality
> in the api.
> 
> I'll file a PR for debuginfod.

Filed PR27673 - [debuginfod] Handle source requests for same buildid more
efficiently

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #26 from Tom de Vries <vries at gcc dot gnu.org> ---
Filed gdb spinoff PR 27678 - "[gdb/symtab] Use debuginfod sourcelist api to
test for file existence".

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

--- Comment #27 from Tom de Vries <vries at gcc dot gnu.org> ---
(In reply to Tom de Vries from comment #17)
> We could do a hack in the debuginfod client that if
> https://debuginfod.opensuse.org/ is queried and the file doesn't start with
> /usr/src/debug, we just return ENOENT.

Filed here: https://bugzilla.suse.com/show_bug.cgi?id=1184257

-- 
You are receiving this mail because:
You are on the CC list for the bug.

[Bug testsuite/27667] FAIL: gdb.mi/mi-sym-info.exp: List all functions from debug information only (timeout)

[vries at gcc dot gnu.org]

https://sourceware.org/bugzilla/show_bug.cgi?id=27667

Tom de Vries <vries at gcc dot gnu.org> changed:

           What    |Removed                     |Added
----------------------------------------------------------------------------
   Target Milestone|---                         |11.1
                 CC|                            |vries at gcc dot gnu.org
             Status|NEW                         |RESOLVED
         Resolution|---                         |FIXED

--- Comment #28 from Tom de Vries <vries at gcc dot gnu.org> ---
The testsuite FAIL is fixed, and remaining issues have been moved elsewhere,
marking resolved-fixed.

-- 
You are receiving this mail because:
You are on the CC list for the bug.