Mirror of the gdb-patches mailing list
 help / color / mirror / Atom feed
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>

  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