From: Luis Machado <luis.machado@arm.com>
To: Andrew Burgess <aburgess@redhat.com>, gdb-patches@sourceware.org
Subject: Re: [PATCH 4/4] gdb/testsuite: track if a caching proc calls gdb_exit or not
Date: Thu, 15 Aug 2024 07:03:42 +0100 [thread overview]
Message-ID: <8dde1d9a-4de3-44d6-9f4c-10820f78af5b@arm.com> (raw)
In-Reply-To: <875xs3xcnm.fsf@redhat.com>
On 8/14/24 18:00, Andrew Burgess wrote:
> Luis Machado <luis.machado@arm.com> writes:
>
>> On 8/13/24 17:30, Andrew Burgess wrote:
>>> Luis Machado <luis.machado@arm.com> writes:
>>>
>>>> On 8/8/24 15:50, Andrew Burgess wrote:
>>>>> Luis Machado <luis.machado@arm.com> writes:
>>>>>
>>>>>> On 8/8/24 11:50, Luis Machado wrote:
>>>>>>> On 8/8/24 11:20, Luis Machado wrote:
>>>>>>>> On 8/7/24 15:31, Andrew Burgess wrote:
>>>>>>>>> Luis Machado <luis.machado@arm.com> writes:
>>>>>>>>>
>>>>>>>>>> Hi Andrew,
>>>>>>>>>>
>>>>>>>>>> On 6/3/24 19:16, Andrew Burgess wrote:
>>>>>>>>>>> After a recent patch review I asked myself why can_spawn_for_attach
>>>>>>>>>>> exists. This proc currently does some checks, and then calls
>>>>>>>>>>> can_spawn_for_attach_1 which is an actual caching proc.
>>>>>>>>>>>
>>>>>>>>>>> The answer is that can_spawn_for_attach exists in order to call
>>>>>>>>>>> gdb_exit the first time can_spawn_for_attach is called within any test
>>>>>>>>>>> script.
>>>>>>>>>>>
>>>>>>>>>>> The reason this is useful is that can_spawn_for_attach_1 calls
>>>>>>>>>>> gdb_exit. If imagine the user calling can_spawn_for_attach_1 directly
>>>>>>>>>>> then a problem might exist. Imagine a test written like this:
>>>>>>>>>>>
>>>>>>>>>>> gdb_start
>>>>>>>>>>>
>>>>>>>>>>> if { [can_spawn_for_attach_1] } {
>>>>>>>>>>> ... do stuff that assumes GDB is running ...
>>>>>>>>>>> }
>>>>>>>>>>>
>>>>>>>>>>> If this test is NOT the first test run, and if an earlier test calls
>>>>>>>>>>> can_spawn_for_attach_1, then when the above test is run the
>>>>>>>>>>> can_spawn_for_attach_1 call will return the cached value and gdb_exit
>>>>>>>>>>> will not be called.
>>>>>>>>>>>
>>>>>>>>>>> But, if the above test IS the first test run then
>>>>>>>>>>> can_spawn_for_attach_1 will not returned the cached value, but will
>>>>>>>>>>> instead compute the cached value, a process that ends up calling
>>>>>>>>>>> gdb_exit. When the body of the if is executed GDB would no longer be
>>>>>>>>>>> running and the test would fail!
>>>>>>>>>>>
>>>>>>>>>>> So can_spawn_for_attach was added which ensures that we _always_ call
>>>>>>>>>>> gdb_exit the first time can_spawn_for_attach is called within a single
>>>>>>>>>>> test script, this ensures that in the above case, even if the above is
>>>>>>>>>>> not the first test run, gdb_exit will still be called. This avoids
>>>>>>>>>>> some hidden bugs in the testsuite.
>>>>>>>>>>>
>>>>>>>>>>> However, what I observe is that can_spawn_for_attach is not the only
>>>>>>>>>>> caching proc that calls gdb_exit. Why does can_spawn_for_attach get
>>>>>>>>>>> special treatment when surely the same issue exists for any other
>>>>>>>>>>> caching proc that calls gdb_exit?
>>>>>>>>>>>
>>>>>>>>>>> I think a better solution is to move the logic from
>>>>>>>>>>> can_spawn_for_attach into cache.exp and generalise it so that it
>>>>>>>>>>> applies to all caching procs.
>>>>>>>>>>>
>>>>>>>>>>> This commit does this by:
>>>>>>>>>>>
>>>>>>>>>>> 1. When the underlying caching proc is executed we wrap gdb_exit.
>>>>>>>>>>> This wrapper sets a global to true if gdb_exit is called. The
>>>>>>>>>>> value of this global is stored in gdb_data_cache (using a ',exit'
>>>>>>>>>>> suffix), and also written to the cache file if appropriate.
>>>>>>>>>>>
>>>>>>>>>>> 2. When a cached value is returned from gdb_do_cache, if the
>>>>>>>>>>> underlying proc would have called gdb_exit, and if this is the
>>>>>>>>>>> first use of the caching proc in this test script, then we call
>>>>>>>>>>> gdb_exit.
>>>>>>>>>>>
>>>>>>>>>>> When storing the ',exit' value into the on-disk cache file, the flag
>>>>>>>>>>> value is stored on a second line. Currently every cached value only
>>>>>>>>>>> occupies a single line, and a check is added to ensure this remains
>>>>>>>>>>> true in the future.
>>>>>>>>>>>
>>>>>>>>>>> One issue did come up in testing, a FAIL in gdb.base/break-interp.exp,
>>>>>>>>>>> this was caused by can_spawn_for_attach_1 calling gdb_start without
>>>>>>>>>>> first calling gdb_exit. Under the old way of doing things
>>>>>>>>>>> can_spawn_for_attach would call gdb_exit _before_ possibly calling the
>>>>>>>>>>> actual caching proc. Under the new scheme gdb_exit is called _after_
>>>>>>>>>>> calling the actual caching proc. What was happening was that
>>>>>>>>>>> break-interp.exp would leave GDB running then call
>>>>>>>>>>> can_spawn_for_attach, when the test in can_spawn_for_attach_1 tried to
>>>>>>>>>>> attach to the inferior, state left in the running GDB would cause some
>>>>>>>>>>> unexpected behaviour. Fixed by having can_spawn_for_attach_1 call
>>>>>>>>>>> gdb_exit before calling gdb_start, this ensures we have a fresh GDB.
>>>>>>>>>>>
>>>>>>>>>>> With this done can_spawn_for_attach_1 can be renamed to
>>>>>>>>>>> can_spawn_for_attach, and the existing can_spawn_for_attach can be
>>>>>>>>>>> deleted.
>>>>>>>>>>> ---
>>>>>>>>>>> gdb/testsuite/lib/cache.exp | 86 +++++++++++++++++++++++++++++++------
>>>>>>>>>>> gdb/testsuite/lib/gdb.exp | 83 +++++++++--------------------------
>>>>>>>>>>> 2 files changed, 93 insertions(+), 76 deletions(-)
>>>>>>>>>>>
>>>>>>>>>>> diff --git a/gdb/testsuite/lib/cache.exp b/gdb/testsuite/lib/cache.exp
>>>>>>>>>>> index e7b9114058b..fef065ec8b0 100644
>>>>>>>>>>> --- a/gdb/testsuite/lib/cache.exp
>>>>>>>>>>> +++ b/gdb/testsuite/lib/cache.exp
>>>>>>>>>>> @@ -46,6 +46,40 @@ proc gdb_do_cache_wrap {real_name args} {
>>>>>>>>>>> return $result
>>>>>>>>>>> }
>>>>>>>>>>>
>>>>>>>>>>> +# Global written to by wrap_gdb_exit. Set to true if wrap_gdb_exit is
>>>>>>>>>>> +# called.
>>>>>>>>>>> +
>>>>>>>>>>> +set gdb_exit_called false
>>>>>>>>>>> +
>>>>>>>>>>> +# Wrapper around gdb_exit. Use with_override to replace gdb_exit with
>>>>>>>>>>> +# wrap_gdb_exit, the original gdb_exit is renamed to orig_gdb_exit.
>>>>>>>>>>> +
>>>>>>>>>>> +proc wrap_gdb_exit {} {
>>>>>>>>>>> + set ::gdb_exit_called true
>>>>>>>>>>> + orig_gdb_exit
>>>>>>>>>>> +}
>>>>>>>>>>> +
>>>>>>>>>>> +# If DO_EXIT is false then this proc does nothing. If DO_EXIT is true
>>>>>>>>>>> +# then call gdb_exit the first time this proc is called for each
>>>>>>>>>>> +# unique value of NAME within a single test. Every subsequent time
>>>>>>>>>>> +# this proc is called within a single test (for a given value of
>>>>>>>>>>> +# NAME), don't call gdb_exit.
>>>>>>>>>>> +
>>>>>>>>>>> +proc gdb_cache_maybe_gdb_exit { name do_exit } {
>>>>>>>>>>> + if { !$do_exit } {
>>>>>>>>>>> + return
>>>>>>>>>>> + }
>>>>>>>>>>> +
>>>>>>>>>>> + # To track if this proc has been called for NAME we create a
>>>>>>>>>>> + # global variable. In gdb_cleanup_globals (see gdb.exp) this
>>>>>>>>>>> + # global will be deleted when the test has finished.
>>>>>>>>>>> + set global_name __${name}__cached_gdb_exit_called
>>>>>>>>>>> + if { ![info exists ::${global_name}] } {
>>>>>>>>>>> + gdb_exit
>>>>>>>>>>> + set ::${global_name} true
>>>>>>>>>>> + }
>>>>>>>>>>> +}
>>>>>>>>>>> +
>>>>>>>>>>> # A helper for gdb_caching_proc that handles the caching.
>>>>>>>>>>>
>>>>>>>>>>> proc gdb_do_cache {name args} {
>>>>>>>>>>> @@ -71,10 +105,12 @@ proc gdb_do_cache {name args} {
>>>>>>>>>>>
>>>>>>>>>>> set is_cached 0
>>>>>>>>>>> if {[info exists gdb_data_cache(${cache_name},value)]} {
>>>>>>>>>>> - set cached $gdb_data_cache(${cache_name},value)
>>>>>>>>>>> - verbose "$name: returning '$cached' from cache" 2
>>>>>>>>>>> + set cached_value $gdb_data_cache(${cache_name},value)
>>>>>>>>>>> + set cached_exit $gdb_data_cache(${cache_name},exit)
>>>>>>>>>>> + verbose "$name: returning '$cached_value' from cache" 2
>>>>>>>>>>> if { $cache_verify == 0 } {
>>>>>>>>>>> - return $cached
>>>>>>>>>>> + gdb_cache_maybe_gdb_exit $name $cached_exit
>>>>>>>>>>> + return $cached_value
>>>>>>>>>>> }
>>>>>>>>>>> set is_cached 1
>>>>>>>>>>> }
>>>>>>>>>>> @@ -83,24 +119,46 @@ proc gdb_do_cache {name args} {
>>>>>>>>>>> set cache_filename [make_gdb_parallel_path cache $cache_name]
>>>>>>>>>>> if {[file exists $cache_filename]} {
>>>>>>>>>>> set fd [open $cache_filename]
>>>>>>>>>>> - set gdb_data_cache(${cache_name},value) [read -nonewline $fd]
>>>>>>>>>>> + set content [split [read -nonewline $fd] \n]
>>>>>>>>>>> close $fd
>>>>>>>>>>> - set cached $gdb_data_cache(${cache_name},value)
>>>>>>>>>>> - verbose "$name: returning '$cached' from file cache" 2
>>>>>>>>>>> + set gdb_data_cache(${cache_name},value) [lindex $content 0]
>>>>>>>>>>> + set gdb_data_cache(${cache_name},exit) [lindex $content 1]
>>>>>>>>>>> + set cached_value $gdb_data_cache(${cache_name},value)
>>>>>>>>>>> + set cached_exit $gdb_data_cache(${cache_name},exit)
>>>>>>>>>>> + verbose "$name: returning '$cached_value' from file cache" 2
>>>>>>>>>>> if { $cache_verify == 0 } {
>>>>>>>>>>> - return $cached
>>>>>>>>>>> + gdb_cache_maybe_gdb_exit $name $cached_exit
>>>>>>>>>>> + return $cached_value
>>>>>>>>>>> }
>>>>>>>>>>> set is_cached 1
>>>>>>>>>>> }
>>>>>>>>>>> }
>>>>>>>>>>>
>>>>>>>>>>> - set real_name gdb_real__$name
>>>>>>>>>>> - set gdb_data_cache(${cache_name},value) [gdb_do_cache_wrap $real_name {*}$args]
>>>>>>>>>>> + set ::gdb_exit_called false
>>>>>>>>>>> + with_override gdb_exit wrap_gdb_exit orig_gdb_exit {
>>>>>>>>>>> + set real_name gdb_real__$name
>>>>>>>>>>> + set gdb_data_cache(${cache_name},value) [gdb_do_cache_wrap $real_name {*}$args]
>>>>>>>>>>> + }
>>>>>>>>>>> + set gdb_data_cache(${cache_name},exit) $::gdb_exit_called
>>>>>>>>>>> +
>>>>>>>>>>> + # If a value being stored in the cache contains a newline then
>>>>>>>>>>> + # when we try to read the value back from an on-disk cache file
>>>>>>>>>>> + # we'll interpret the second line of the value as the ',exit' value.
>>>>>>>>>>> + if { [regexp "\[\r\n\]" $gdb_data_cache(${cache_name},value)] } {
>>>>>>>>>>> + set computed_value $gdb_data_cache(${cache_name},value)
>>>>>>>>>>> + error "Newline found in value for $cache_name: $computed_value"
>>>>>>>>>>> + }
>>>>>>>>>>> +
>>>>>>>>>>> if { $cache_verify == 1 && $is_cached == 1 } {
>>>>>>>>>>> - set computed $gdb_data_cache(${cache_name},value)
>>>>>>>>>>> - if { $cached != $computed } {
>>>>>>>>>>> - error [join [list "Inconsistent results for $cache_name:"
>>>>>>>>>>> - "cached: $cached vs. computed: $computed"]]
>>>>>>>>>>> + set computed_value $gdb_data_cache(${cache_name},value)
>>>>>>>>>>> + set computed_exit $gdb_data_cache(${cache_name},exit)
>>>>>>>>>>> + if { $cached_value != $computed_value } {
>>>>>>>>>>> + error [join [list "Inconsistent value results for $cache_name:"
>>>>>>>>>>> + "cached: $cached_value vs. computed: $computed_value"]]
>>>>>>>>>>> + }
>>>>>>>>>>> + if { $cached_exit != $computed_exit } {
>>>>>>>>>>> + error [join [list "Inconsistent exit results for $cache_name:"
>>>>>>>>>>> + "cached: $cached_exit vs. computed: $computed_exit"]]
>>>>>>>>>>> }
>>>>>>>>>>> }
>>>>>>>>>>>
>>>>>>>>>>> @@ -110,9 +168,11 @@ proc gdb_do_cache {name args} {
>>>>>>>>>>> # Make sure to write the results file atomically.
>>>>>>>>>>> set fd [open $cache_filename.[pid] w]
>>>>>>>>>>> puts $fd $gdb_data_cache(${cache_name},value)
>>>>>>>>>>> + puts $fd $gdb_data_cache(${cache_name},exit)
>>>>>>>>>>> close $fd
>>>>>>>>>>> file rename -force -- $cache_filename.[pid] $cache_filename
>>>>>>>>>>> }
>>>>>>>>>>> + gdb_cache_maybe_gdb_exit $name $gdb_data_cache(${cache_name},exit)
>>>>>>>>>>> return $gdb_data_cache(${cache_name},value)
>>>>>>>>>>> }
>>>>>>>>>>>
>>>>>>>>>>> diff --git a/gdb/testsuite/lib/gdb.exp b/gdb/testsuite/lib/gdb.exp
>>>>>>>>>>> index 8235d4f28eb..d29fd740f91 100644
>>>>>>>>>>> --- a/gdb/testsuite/lib/gdb.exp
>>>>>>>>>>> +++ b/gdb/testsuite/lib/gdb.exp
>>>>>>>>>>> @@ -6186,14 +6186,23 @@ proc gdb_exit { } {
>>>>>>>>>>> catch default_gdb_exit
>>>>>>>>>>> }
>>>>>>>>>>>
>>>>>>>>>>> -# Helper function for can_spawn_for_attach. Try to spawn and attach, and
>>>>>>>>>>> -# return 0 only if we cannot attach because it's unsupported.
>>>>>>>>>>> -
>>>>>>>>>>> -gdb_caching_proc can_spawn_for_attach_1 {} {
>>>>>>>>>>> - # For the benefit of gdb-caching-proc-consistency.exp, which
>>>>>>>>>>> - # calls can_spawn_for_attach_1 directly. Keep in sync with
>>>>>>>>>>> - # can_spawn_for_attach.
>>>>>>>>>>> - if { [is_remote target] || [target_info exists use_gdb_stub] } {
>>>>>>>>>>> +# Return true if we can spawn a program on the target and attach to
>>>>>>>>>>> +# it.
>>>>>>>>>>> +
>>>>>>>>>>> +gdb_caching_proc can_spawn_for_attach {} {
>>>>>>>>>>> + # We use exp_pid to get the inferior's pid, assuming that gives
>>>>>>>>>>> + # back the pid of the program. On remote boards, that would give
>>>>>>>>>>> + # us instead the PID of e.g., the ssh client, etc.
>>>>>>>>>>> + if {[is_remote target]} {
>>>>>>>>>>> + verbose -log "can't spawn for attach (target is remote)"
>>>>>>>>>>> + return 0
>>>>>>>>>>> + }
>>>>>>>>>>> +
>>>>>>>>>>> + # The "attach" command doesn't make sense when the target is
>>>>>>>>>>> + # stub-like, where GDB finds the program already started on
>>>>>>>>>>> + # initial connection.
>>>>>>>>>>> + if {[target_info exists use_gdb_stub]} {
>>>>>>>>>>> + verbose -log "can't spawn for attach (target is stub)"
>>>>>>>>>>> return 0
>>>>>>>>>>> }
>>>>>>>>>>>
>>>>>>>>>>> @@ -6218,6 +6227,9 @@ gdb_caching_proc can_spawn_for_attach_1 {} {
>>>>>>>>>>> set test_spawn_id [spawn_wait_for_attach_1 $obj]
>>>>>>>>>>> remote_file build delete $obj
>>>>>>>>>>>
>>>>>>>>>>> + # In case GDB is already running.
>>>>>>>>>>> + gdb_exit
>>>>>>>>>>> +
>>>>>>>>>>> gdb_start
>>>>>>>>>>>
>>>>>>>>>>> set test_pid [spawn_id_get_pid $test_spawn_id]
>>>>>>>>>>> @@ -6239,61 +6251,6 @@ gdb_caching_proc can_spawn_for_attach_1 {} {
>>>>>>>>>>> return $res
>>>>>>>>>>> }
>>>>>>>>>>>
>>>>>>>>>>> -# Return true if we can spawn a program on the target and attach to
>>>>>>>>>>> -# it. Calls gdb_exit for the first call in a test-case.
>>>>>>>>>>> -
>>>>>>>>>>> -proc can_spawn_for_attach { } {
>>>>>>>>>>> - # We use exp_pid to get the inferior's pid, assuming that gives
>>>>>>>>>>> - # back the pid of the program. On remote boards, that would give
>>>>>>>>>>> - # us instead the PID of e.g., the ssh client, etc.
>>>>>>>>>>> - if {[is_remote target]} {
>>>>>>>>>>> - verbose -log "can't spawn for attach (target is remote)"
>>>>>>>>>>> - return 0
>>>>>>>>>>> - }
>>>>>>>>>>> -
>>>>>>>>>>> - # The "attach" command doesn't make sense when the target is
>>>>>>>>>>> - # stub-like, where GDB finds the program already started on
>>>>>>>>>>> - # initial connection.
>>>>>>>>>>> - if {[target_info exists use_gdb_stub]} {
>>>>>>>>>>> - verbose -log "can't spawn for attach (target is stub)"
>>>>>>>>>>> - return 0
>>>>>>>>>>> - }
>>>>>>>>>>> -
>>>>>>>>>>> - # The normal sequence to use for a runtime test like
>>>>>>>>>>> - # can_spawn_for_attach_1 is:
>>>>>>>>>>> - # - gdb_exit (don't use a running gdb, we don't know what state it is in),
>>>>>>>>>>> - # - gdb_start (start a new gdb), and
>>>>>>>>>>> - # - gdb_exit (cleanup).
>>>>>>>>>>> - #
>>>>>>>>>>> - # By making can_spawn_for_attach_1 a gdb_caching_proc, we make it
>>>>>>>>>>> - # unpredictable which test-case will call it first, and consequently a
>>>>>>>>>>> - # test-case may pass in say a full test run, but fail when run
>>>>>>>>>>> - # individually, due to a can_spawn_for_attach call in a location where a
>>>>>>>>>>> - # gdb_exit (as can_spawn_for_attach_1 does) breaks things.
>>>>>>>>>>> - # To avoid this, we move the initial gdb_exit out of
>>>>>>>>>>> - # can_spawn_for_attach_1, guaranteeing that we end up in the same state
>>>>>>>>>>> - # regardless of whether can_spawn_for_attach_1 is called. However, that
>>>>>>>>>>> - # is only necessary for the first call in a test-case, so cache the result
>>>>>>>>>>> - # in a global (which should be reset after each test-case) to keep track
>>>>>>>>>>> - # of that.
>>>>>>>>>>> - #
>>>>>>>>>>> - # In summary, we distinguish between three cases:
>>>>>>>>>>> - # - first call in first test-case. Executes can_spawn_for_attach_1.
>>>>>>>>>>> - # Calls gdb_exit, gdb_start, gdb_exit.
>>>>>>>>>>> - # - first call in following test-cases. Uses cached result of
>>>>>>>>>>> - # can_spawn_for_attach_1. Calls gdb_exit.
>>>>>>>>>>> - # - rest. Use cached result in cache_can_spawn_for_attach_1. Calls no
>>>>>>>>>>> - # gdb_start or gdb_exit.
>>>>>>>>>>> - global cache_can_spawn_for_attach_1
>>>>>>>>>>> - if { [info exists cache_can_spawn_for_attach_1] } {
>>>>>>>>>>> - return $cache_can_spawn_for_attach_1
>>>>>>>>>>> - }
>>>>>>>>>>> - gdb_exit
>>>>>>>>>>> -
>>>>>>>>>>> - set cache_can_spawn_for_attach_1 [can_spawn_for_attach_1]
>>>>>>>>>>> - return $cache_can_spawn_for_attach_1
>>>>>>>>>>> -}
>>>>>>>>>>> -
>>>>>>>>>>> # Centralize the failure checking of "attach" command.
>>>>>>>>>>> # Return 0 if attach failed, otherwise return 1.
>>>>>>>>>>>
>>>>>>>>>>
>>>>>>>>>> This is a bit after the fact, but I tracked down some aarch64 sme test regressions
>>>>>>>>>> to this particular patch. I'm still investigating exactly why it stopped working, but I
>>>>>>>>>> can tell it only happens if we run 2 or more tests in the same run. It is not
>>>>>>>>>> clear if making things parallel has an impact, or if it is just the fact we
>>>>>>>>>> run 2+ tests in the same run.
>>>>>>>>>>
>>>>>>>>>> I suspect we may be calling gdb_exit when we shouldn't, and then things just
>>>>>>>>>> stop working.
>>>>>>>>>>
>>>>>>>>>> ---
>>>>>>>>>>
>>>>>>>>>> Running target unix
>>>>>>>>>> Using /usr/share/dejagnu/baseboards/unix.exp as board description file for target.
>>>>>>>>>> Using /usr/share/dejagnu/config/unix.exp as generic interface file for target.
>>>>>>>>>> Using repos/binutils-gdb/gdb/testsuite/config/unix.exp as tool-and-target-specific interface file.
>>>>>>>>>> Running repos/binutils-gdb/gdb/testsuite/gdb.arch/aarch64-sme-core-0.exp ...
>>>>>>>>>> Running repos/binutils-gdb/gdb/testsuite/gdb.arch/aarch64-sme-regs-unavailable-3.exp ...
>>>>>>>>>> ERROR: no fileid for ubuntu
>>>>>>>>>> ERROR: no fileid for ubuntu
>>>>>>>>>> ERROR: no fileid for ubuntu
>>>>>>>>>> ERROR: no fileid for ubuntu
>>>>>>>>>> FAIL: gdb.arch/aarch64-sme-regs-unavailable-3.exp: prctl, vl=32 svl=256: check_regs: incorrect ZA state
>>>>>>>>>> ERROR: no fileid for ubuntu
>>>>>>>>>> ERROR: no fileid for ubuntu
>>>>>>>>>> ERROR: no fileid for ubuntu
>>>>>>>>>> ERROR: no fileid for ubuntu
>>>>>>>>>> ERROR: no fileid for ubuntu
>>>>>>>>>> ERROR: no fileid for ubuntu
>>>>>>>>>> ERROR: no fileid for ubuntu
>>>>>>>>>> FAIL: gdb.arch/aarch64-sme-regs-unavailable-3.exp: gdb, vl=32 svl=256: check_regs: incorrect ZA state
>>>>>>>>>
>>>>>>>>> Luis,
>>>>>>>>>
>>>>>>>>> Could you please test the patch below to see if this fixes the issues
>>>>>>>>> you are seeing. This is also running through local testing at my side,
>>>>>>>>> but I thought I'd get your feedback early.
>>>>>>>>>
>>>>>>>>> Thanks,
>>>>>>>>> Andrew
>>>>>>>>>
>>>>>>>>
>>>>>>>> Well, it's one of those things I guess. I saw some errors the first time I tried the patch, but then
>>>>>>>> I couldn't reproduce it anymore. So far it's been running pretty smoothly for both parallel and
>>>>>>>> serialized runs. So I'd say this patch does the job and we should push it.
>>>>>>>> Thanks for putting it together.
>>>>>>>>
>>>>>>>> I'll do a complete run overnight just to make sure, but it will take a little bit before I can report
>>>>>>>> it.
>>>>>>>
>>>>>>> Of course, a short while after sending this, I managed to reproduce the error.
>>>>>>>
>>>>>>> I'm running the following:
>>>>>>>
>>>>>>> make check-gdb TESTS=gdb.arch/*.exp -j$(nproc). Let me fetch some more information.
>>>>>>
>>>>>> It seems we're hitting the same situation with aarch64_initialize_sve_information via aarch64_supports_sve_vl,
>>>>>> which causes the testsuite to call gdb_exit.
>>>>>>
>>>>>> It is as you described, it is the first time we're running gdb.arch/aarch64-sme-core-2.exp, so we go through
>>>>>> caching etc.
>>>>>>
>>>>>> ---
>>>>>>
>>>>>> Running builds/binutils-gdb/gdb/testsuite/../../../../repos/binutils-gdb/gdb/testsuite/gdb.arch/aarch64-sme-core-2.exp ...
>>>>>> gdb_caching_proc allow_aarch64_sve_tests caused gdb_exit to be called
>>>>>> stack trace is Stack trace:
>>>>>> gdb_cache_maybe_gdb_exit proc_name='allow_aarch64_sve_tests' cache_name='unix/allow_aarch64_sve_tests'
>>>>>> gdb_do_cache name='allow_aarch64_sve_tests' args=''
>>>>>> allow_aarch64_sve_tests
>>>>>
>>>>> This makes sense assuming that this is not the first time
>>>>> `allow_aarch64_sve_tests` was called in this test run. This looks like
>>>>> its using the previously cached result.
>>>>>
>>>>> If all had gone as expected then this should have marked `gdb_exit` as
>>>>> having been called for both `allow_aarch64_sve_tests` and
>>>>> `aarch64_initialize_sve_information`, though given what happens below I
>>>>> guess that the marker for `aarch64_initialize_sve_information` isn't
>>>>> being created correctly.
>>>>>
>>>>> You could try applying this patch:
>>>>>
>>>>> ### START ###
>>>>>
>>>>> diff --git a/gdb/testsuite/lib/cache.exp b/gdb/testsuite/lib/cache.exp
>>>>> index 7e1eae9259e..d6027b352f1 100644
>>>>> --- a/gdb/testsuite/lib/cache.exp
>>>>> +++ b/gdb/testsuite/lib/cache.exp
>>>>> @@ -98,6 +98,7 @@ proc gdb_cache_maybe_gdb_exit { proc_name cache_name } {
>>>>> set ::${global_name} true
>>>>>
>>>>> foreach other_name $gdb_data_cache(${cache_name},also_called) {
>>>>> + verbose -log " gdb_caching_proc $other_name also called exit"
>>>>> set global_name __${other_name}__cached_gdb_exit_called
>>>>> set ::${global_name} true
>>>>> }
>>>>> @@ -110,6 +111,8 @@ proc gdb_do_cache {name args} {
>>>>> global gdb_data_cache objdir
>>>>> global GDB_PARALLEL
>>>>>
>>>>> + verbose -log "gdb_do_cache: $name ( $args )"
>>>>> +
>>>>> # Normally, if we have a cached value, we skip computation and return
>>>>> # the cached value. If set to 1, instead don't skip computation and
>>>>> # verify against the cached value.
>>>>>
>>>>> ### END ###
>>>>>
>>>>> which will log the "other" caching procs that are recorded as having
>>>>> been called.
>>>>>
>>>>> Also, if you are running `make -j...." then you can look into the cache
>>>>> files which will be 'gdb/testsuite/cache/unix/allow_aarch64_sve_tests'
>>>>> and 'gdb/testsuite/cache/unix/aarch64_initialize_sve_information' as
>>>>> this should also include the information about the nested caching proc
>>>>> structure.
>>>>>
>>>>>>
>>>>>> gdb_caching_proc allow_aarch64_sme_tests caused gdb_exit to be called
>>>>>> stack trace is Stack trace:
>>>>>> gdb_cache_maybe_gdb_exit proc_name='allow_aarch64_sme_tests' cache_name='unix/allow_aarch64_sme_tests'
>>>>>> gdb_do_cache name='allow_aarch64_sme_tests' args=''
>>>>>> allow_aarch64_sme_tests
>>>>>>
>>>>>> get_compiler_info: gcc-13-2-0
>>>>>> Executing on host: gcc -fno-stack-protector -fdiagnostics-color=never -g3 -march=armv8.5-a+sve -c -g -o builds/binutils-gdb/gdb/testsuite/outputs/gdb.arch/aarch64-sme-core-2/aarch64-sme-core-20.o builds/binutils-gdb/gdb/testsuite/../../../../repos/binutils-gdb/gdb/testsuite/gdb.arch/aarch64-sme-core.c (timeout = 300)
>>>>>> builtin_spawn -ignore SIGHUP gcc -fno-stack-protector -fdiagnostics-color=never -g3 -march=armv8.5-a+sve -c -g -o builds/binutils-gdb/gdb/testsuite/outputs/gdb.arch/aarch64-sme-core-2/aarch64-sme-core-20.o builds/binutils-gdb/gdb/testsuite/../../../../repos/binutils-gdb/gdb/testsuite/gdb.arch/aarch64-sme-core.c
>>>>>> Executing on host: gcc -fno-stack-protector builds/binutils-gdb/gdb/testsuite/outputs/gdb.arch/aarch64-sme-core-2/aarch64-sme-core-20.o -fdiagnostics-color=never -g3 -march=armv8.5-a+sve -g -lm -o builds/binutils-gdb/gdb/testsuite/outputs/gdb.arch/aarch64-sme-core-2/aarch64-sme-core-2 (timeout = 300)
>>>>>> builtin_spawn -ignore SIGHUP gcc -fno-stack-protector builds/binutils-gdb/gdb/testsuite/outputs/gdb.arch/aarch64-sme-core-2/aarch64-sme-core-20.o -fdiagnostics-color=never -g3 -march=armv8.5-a+sve -g -lm -o builds/binutils-gdb/gdb/testsuite/outputs/gdb.arch/aarch64-sme-core-2/aarch64-sme-core-2
>>>>>> builtin_spawn builds/binutils-gdb/gdb/testsuite/../../gdb/gdb -nw -nx -q -iex set height 0 -iex set width 0 -data-directory builds/binutils-gdb/gdb/data-directory
>>>>>> (gdb) set height 0
>>>>>> (gdb) set width 0
>>>>>> (gdb) dir
>>>>>> Reinitialize source path to empty? (y or n) y
>>>>>> Source directories searched: $cdir:$cwd
>>>>>> (gdb) dir builds/binutils-gdb/gdb/testsuite/../../../../repos/binutils-gdb/gdb/testsuite/gdb.arch
>>>>>> Source directories searched: builds/binutils-gdb/gdb/testsuite/../../../../repos/binutils-gdb/gdb/testsuite/gdb.arch:$cdir:$cwd
>>>>>> (gdb) kill
>>>>>> The program is not being run.
>>>>>> (gdb) file builds/binutils-gdb/gdb/testsuite/outputs/gdb.arch/aarch64-sme-core-2/aarch64-sme-core-2
>>>>>> Reading symbols from builds/binutils-gdb/gdb/testsuite/outputs/gdb.arch/aarch64-sme-core-2/aarch64-sme-core-2...
>>>>>> (gdb) gdb_caching_proc aarch64_initialize_sve_information caused gdb_exit to be called
>>>>>> stack trace is Stack trace:
>>>>>> gdb_cache_maybe_gdb_exit proc_name='aarch64_initialize_sve_information' cache_name='unix/aarch64_initialize_sve_information'
>>>>>> gdb_do_cache name='aarch64_initialize_sve_information' args=''
>>>>>> aarch64_initialize_sve_information
>>>>>> aarch64_supports_sve_vl length='16'
>>>>>> test_sme_core_file id_start='50' id_end='74'
>>>>>
>>>>> This should have been prevented by the earlier allow_aarch64_sve_tests
>>>>> call. Either we failed to correctly spot the relationship between the
>>>>> two caching procs, or we're failing to mark this second one as having
>>>>> been called for some reason...
>>>>>
>>>>> I think we need more data to try and debug this...
>>>>
>>>> I did some more debugging on this, and I think I see what's going on here. I haven't
>>>> checked the flow in detail yet, but hopefully it will ring some bells.
>>>>
>>>> My theory is that your patch's logic is sane, but we're hitting concurrency issues when
>>>> running things in parallel, or parallel enough. For instance, I consistently hit issues when
>>>> using -j4, but I can't hit it with anything below -j4.
>>>>
>>>> I added a couple debugging statements in gdb_do_cache, at the end within the if {[info exists GDB_PARALLEL]} block.
>>>>
>>>> The statements check if the $cache_filename exists and what the contents are, before we rename
>>>> $cache_filename.[pid] to $cache_filename, essentially overwriting the old cache file.
>>>>
>>>> What I saw was the following:
>>>>
>>>> ---
>>>>
>>>> gdb_do_cache: aarch64_initialize_sve_information ( )
>>>> gdb_caching_proc aarch64_initialize_sve_information caused gdb_exit to be called
>>>> Stack trace:
>>>> gdb_cache_maybe_gdb_exit proc_name='aarch64_initialize_sve_information' cache_name='unix/aarch64_initialize_sve_information'
>>>> gdb_do_cache name='aarch64_initialize_sve_information' args=''
>>>> aarch64_initialize_sve_information
>>>> gdb_real__allow_aarch64_sve_tests
>>>> allow_aarch64_sve_tests
>>>>
>>>> XXXX: File builds/binutils-gdb/gdb/testsuite/cache/unix/allow_aarch64_sve_tests already exists before rename.
>>>> XXXX: Old contents: 1 true aarch64_initialize_sve_information
>>>> XXXX: New contents: 1 true {}
>>>> gdb_caching_proc allow_aarch64_sve_tests caused gdb_exit to be called
>>>> Stack trace:
>>>> gdb_cache_maybe_gdb_exit proc_name='allow_aarch64_sve_tests' cache_name='unix/allow_aarch64_sve_tests'
>>>> gdb_do_cache name='allow_aarch64_sve_tests' args=''
>>>> allow_aarch64_sve_tests
>>>>
>>>> ---
>>>>
>>>> So we replace a cache file that contains more information with a copy that has less information.
>>>>
>>>> Eventually we call aarch64_initialize_sve_information again, via a different function, and things go bad.
>>>>
>>>> I'm guessing the logic of updating the cache files needs to be atomic, and we're having some timing issues
>>>> where two (or more) tests try to update the same file and we end up losing information.
>>>
>>> Thanks for doing the leg work on this. The information you provided is
>>> spot on, and it was indeed a timing issue as you predicted. Here's
>>> what's happening:
>>>
>>> In thread #1 we call 'allow_aarch64_sme_tests', there's no cache file
>>> yet we eval the body, this calls 'aarch64_initialize_sme_information'.
>>> So we try to create two cache files in this order:
>>>
>>> unix/aarch64_initialize_sme_information
>>> value: ... whatever ...
>>> exit_called?: true
>>> also_called: {}
>>>
>>> unix/allow_aarch64_sme_tests
>>> value: ... whatever ...
>>> exit_called?: true
>>> also_called: aarch64_initialize_sme_information
>>>
>>> In thread #2 we also call 'allow_aarch64_sme_tests', however, if we
>>> manage to land after the first cache file was created but before the
>>> second file was created then we pick up the cached result for
>>> 'aarch64_initialize_sme_information'.
>>>
>>> And this is where my bug was: I didn't add
>>> 'aarch64_initialize_sme_information' to the also_called list for a
>>> caching proc if we managed to find a cached value. As a result, the
>>> thread #2 cache file looked like this:
>>>
>>> unix/allow_aarch64_sme_tests
>>> value: ... whatever ...
>>> exit_called?: true
>>> also_called: {}
>>>
>>> which then overwrote the first cache file (atomically).
>>>
>>> A later test would call 'allow_aarch64_sme_tests' and then separately
>>> call 'aarch64_initialize_sme_information'. Due to the corrupted cache
>>> file calling 'allow_aarch64_sme_tests' would not mark
>>> 'aarch64_initialize_sme_information' as having been called. So when we
>>> separately call 'aarch64_initialize_sme_information' gdb_exit would be
>>> called which broke the test.
>>>
>>> The fix, of course, is to ensure that when we get a cache hit we still
>>> record the called function as being something that is "also called".
>>>
>>> I have a new patch below (discard the previous patch I sent) which I
>>> think should resole the problems you're having. I'm still running the
>>> full tests on my end, but initial (limited) testing looks good.
>>
>> Great. Glad the information was useful. I gave the attached patch a try and
>> managed to run all the sme tests in parallel and did not see any FAIL's or
>> errors.
>>
>> I'm doing a few more runs just in case, but I think we're good.
>>
>
> That's great news. If I don't hear anything in a couple of days then
> I'll push this fix.
The additional testing finished OK. So this is good. Thanks for putting it together.
Approved-By: Luis Machado <luis.machado@arm.com>
Tested-By: Luis Machado <luis.machado@arm.com>
next prev parent reply other threads:[~2024-08-15 6:04 UTC|newest]
Thread overview: 32+ messages / expand[flat|nested] mbox.gz Atom feed top
2024-06-03 18:16 [PATCH 0/4] gdb/testsuite: remove can_spawn_for_attach_1 Andrew Burgess
2024-06-03 18:16 ` [PATCH 1/4] gdb/testsuite: remove trailing \r from rust_llvm_version result Andrew Burgess
2024-06-04 13:51 ` Tom Tromey
2024-06-05 9:20 ` Andrew Burgess
2024-06-03 18:16 ` [PATCH 2/4] gdb/testsuite: improve with_override Andrew Burgess
2024-06-03 18:16 ` [PATCH 3/4] gdb/testsuite: restructure gdb_data_cache (lib/cache.exp) Andrew Burgess
2024-06-03 18:16 ` [PATCH 4/4] gdb/testsuite: track if a caching proc calls gdb_exit or not Andrew Burgess
2024-08-07 6:05 ` Luis Machado
2024-08-07 9:16 ` Andrew Burgess
2024-08-07 10:00 ` Andrew Burgess
2024-08-07 10:08 ` Luis Machado
2024-08-07 10:12 ` Luis Machado
2024-08-07 12:45 ` Andrew Burgess
2024-08-07 14:31 ` Andrew Burgess
2024-08-07 18:35 ` Luis Machado
2024-08-08 10:20 ` Luis Machado
2024-08-08 10:50 ` Luis Machado
2024-08-08 11:08 ` Luis Machado
2024-08-08 14:50 ` Andrew Burgess
2024-08-09 11:29 ` Luis Machado
2024-08-13 16:30 ` Andrew Burgess
2024-08-14 13:06 ` Luis Machado
2024-08-14 17:00 ` Andrew Burgess
2024-08-15 6:03 ` Luis Machado [this message]
2024-08-20 15:25 ` Andrew Burgess
2024-06-04 9:06 ` [PATCH 0/4] gdb/testsuite: remove can_spawn_for_attach_1 Andrew Burgess
2024-06-05 13:27 ` [PATCHv2 0/2] " Andrew Burgess
2024-06-05 13:27 ` [PATCHv2 1/2] gdb/testsuite: restructure gdb_data_cache (lib/cache.exp) Andrew Burgess
2024-06-05 13:27 ` [PATCHv2 2/2] gdb/testsuite: track if a caching proc calls gdb_exit or not Andrew Burgess
2024-07-28 8:54 ` [PUSHED 0/2] gdb/testsuite: remove can_spawn_for_attach_1 Andrew Burgess
2024-07-28 8:54 ` [PUSHED 1/2] gdb/testsuite: restructure gdb_data_cache (lib/cache.exp) Andrew Burgess
2024-07-28 8:54 ` [PUSHED 2/2] gdb/testsuite: track if a caching proc calls gdb_exit or not Andrew Burgess
Reply instructions:
You may reply publicly to this message via plain-text email
using any one of the following methods:
* Save the following mbox file, import it into your mail client,
and reply-to-all from there: mbox
Avoid top-posting and favor interleaved quoting:
https://en.wikipedia.org/wiki/Posting_style#Interleaved_style
* Reply using the --to, --cc, and --in-reply-to
switches of git-send-email(1):
git send-email \
--in-reply-to=8dde1d9a-4de3-44d6-9f4c-10820f78af5b@arm.com \
--to=luis.machado@arm.com \
--cc=aburgess@redhat.com \
--cc=gdb-patches@sourceware.org \
/path/to/YOUR_REPLY
https://kernel.org/pub/software/scm/git/docs/git-send-email.html
* If your mail client supports setting the In-Reply-To header
via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line
before the message body.
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox