this derivation will be built:
  /nix/store/12scgvpc5a6cc4vykga6xd4m75s3vjqz-niks3-go-unit-tests.drv
building '/nix/store/12scgvpc5a6cc4vykga6xd4m75s3vjqz-niks3-go-unit-tests.drv'
niks3-go-unit-tests> tribuchet: building on mac02
niks3-go-unit-tests> Running client tests...
niks3-go-unit-tests> === RUN   TestDoServerRequestAttachesToken
niks3-go-unit-tests> === PAUSE TestDoServerRequestAttachesToken
niks3-go-unit-tests> === RUN   TestRegisterUploadedObjectReusesConnections
niks3-go-unit-tests> === PAUSE TestRegisterUploadedObjectReusesConnections
niks3-go-unit-tests> === RUN   TestRunGarbageCollection_FinishedOnAnotherReplica
niks3-go-unit-tests> === PAUSE TestRunGarbageCollection_FinishedOnAnotherReplica
niks3-go-unit-tests> === RUN   TestRunGarbageCollection_NotFoundAfterLocalRun
niks3-go-unit-tests> === PAUSE TestRunGarbageCollection_NotFoundAfterLocalRun
niks3-go-unit-tests> === RUN   TestCaseHackSuffix
niks3-go-unit-tests> === PAUSE TestCaseHackSuffix
niks3-go-unit-tests> === RUN   TestFilterOversizedClosures
niks3-go-unit-tests> === PAUSE TestFilterOversizedClosures
niks3-go-unit-tests> === RUN   TestUploadMultipart_PartsInParallel
niks3-go-unit-tests> === PAUSE TestUploadMultipart_PartsInParallel
niks3-go-unit-tests> === RUN   TestPartSizeForNAR
niks3-go-unit-tests> === PAUSE TestPartSizeForNAR
niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer
niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer
niks3-go-unit-tests> === RUN   TestDumpPathCaseHackMatchesNix
niks3-go-unit-tests> --- PASS: TestDumpPathCaseHackMatchesNix (0.10s)
niks3-go-unit-tests> === RUN   TestDumpPathCaseHackCollision
niks3-go-unit-tests> --- PASS: TestDumpPathCaseHackCollision (0.01s)
niks3-go-unit-tests> === RUN   TestDumpPathMatchesNix
niks3-go-unit-tests> === PAUSE TestDumpPathMatchesNix
niks3-go-unit-tests> === RUN   TestDumpPathSingleFile
niks3-go-unit-tests> === PAUSE TestDumpPathSingleFile
niks3-go-unit-tests> === RUN   TestDumpPathWriterError
niks3-go-unit-tests> === PAUSE TestDumpPathWriterError
niks3-go-unit-tests> === RUN   TestEncodeNixBase32
niks3-go-unit-tests> === PAUSE TestEncodeNixBase32
niks3-go-unit-tests> === RUN   TestEncodeNixBase32WithRealHash
niks3-go-unit-tests> === PAUSE TestEncodeNixBase32WithRealHash
niks3-go-unit-tests> === RUN   TestConvertHashToNix32
niks3-go-unit-tests> === PAUSE TestConvertHashToNix32
niks3-go-unit-tests> === RUN   TestGetStorePathHash
niks3-go-unit-tests> === PAUSE TestGetStorePathHash
niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility
niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility
niks3-go-unit-tests> === RUN   TestParsePathInfoJSON
niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON
niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths
niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths
niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility
niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility
niks3-go-unit-tests> === RUN   TestRateLimiterFeedback
niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback
niks3-go-unit-tests> === RUN   TestRateLimiterFeedback_400DoesNotCountAsSuccess
niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess
niks3-go-unit-tests> === RUN   TestResolveStorePath
niks3-go-unit-tests> === PAUSE TestResolveStorePath
niks3-go-unit-tests> === RUN   TestDoWithRetry_BodyReplayedViaGetBody
niks3-go-unit-tests> === PAUSE TestDoWithRetry_BodyReplayedViaGetBody
niks3-go-unit-tests> === RUN   TestRetryTimeoutOutlastsMaxRetries
niks3-go-unit-tests> === PAUSE TestRetryTimeoutOutlastsMaxRetries
niks3-go-unit-tests> === RUN   TestShellSplit
niks3-go-unit-tests> === PAUSE TestShellSplit
niks3-go-unit-tests> === RUN   TestShellSplitErrors
niks3-go-unit-tests> === PAUSE TestShellSplitErrors
niks3-go-unit-tests> === RUN   TestStreamPushReportsEveryPath
niks3-go-unit-tests> === PAUSE TestStreamPushReportsEveryPath
niks3-go-unit-tests> === RUN   TestStreamPushBatchesUnderLoad
niks3-go-unit-tests> === PAUSE TestStreamPushBatchesUnderLoad
niks3-go-unit-tests> === RUN   TestStreamPushIsolatesFailures
niks3-go-unit-tests> === PAUSE TestStreamPushIsolatesFailures
niks3-go-unit-tests> === RUN   TestStreamPushGivesUpOnDeadServer
niks3-go-unit-tests> === PAUSE TestStreamPushGivesUpOnDeadServer
niks3-go-unit-tests> === RUN   TestStreamPushRequestLine
niks3-go-unit-tests> === PAUSE TestStreamPushRequestLine
niks3-go-unit-tests> === RUN   TestStreamPushReportsSignatures
niks3-go-unit-tests> === PAUSE TestStreamPushReportsSignatures
niks3-go-unit-tests> === RUN   TestStreamPushReportsSkippedPaths
niks3-go-unit-tests> === PAUSE TestStreamPushReportsSkippedPaths
niks3-go-unit-tests> === RUN   TestClientSignaturesByStorePath
niks3-go-unit-tests> === PAUSE TestClientSignaturesByStorePath
niks3-go-unit-tests> === RUN   TestStreamPushStopsWhenCanceled
niks3-go-unit-tests> === PAUSE TestStreamPushStopsWhenCanceled
niks3-go-unit-tests> === RUN   TestSetClientTLS
niks3-go-unit-tests> === PAUSE TestSetClientTLS
niks3-go-unit-tests> === RUN   TestSetClientTLSDoesNotMutateDefaultTransport
niks3-go-unit-tests> === PAUSE TestSetClientTLSDoesNotMutateDefaultTransport
niks3-go-unit-tests> === RUN   TestSetClientTLSErrors
niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors
niks3-go-unit-tests> === RUN   TestStaticToken
niks3-go-unit-tests> === PAUSE TestStaticToken
niks3-go-unit-tests> === RUN   TestFileTokenReadsAndCaches
niks3-go-unit-tests> === PAUSE TestFileTokenReadsAndCaches
niks3-go-unit-tests> === RUN   TestFileTokenMissing
niks3-go-unit-tests> === PAUSE TestFileTokenMissing
niks3-go-unit-tests> === RUN   TestFileTokenEmpty
niks3-go-unit-tests> === PAUSE TestFileTokenEmpty
niks3-go-unit-tests> === RUN   TestScriptTokenNoExpiryRerunsEveryCall
niks3-go-unit-tests> === PAUSE TestScriptTokenNoExpiryRerunsEveryCall
niks3-go-unit-tests> === RUN   TestScriptTokenCachesUntilRefresh
niks3-go-unit-tests> === PAUSE TestScriptTokenCachesUntilRefresh
niks3-go-unit-tests> === RUN   TestScriptTokenEmptyToken
niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyToken
niks3-go-unit-tests> === RUN   TestScriptTokenBadJSON
niks3-go-unit-tests> === PAUSE TestScriptTokenBadJSON
niks3-go-unit-tests> === RUN   TestScriptTokenScriptFails
niks3-go-unit-tests> === PAUSE TestScriptTokenScriptFails
niks3-go-unit-tests> === RUN   TestScriptTokenEmptyCommand
niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyCommand
niks3-go-unit-tests> === CONT  TestDoServerRequestAttachesToken
niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall
niks3-go-unit-tests> === CONT  TestStaticToken
niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)
niks3-go-unit-tests> === CONT  TestRetryTimeoutOutlastsMaxRetries
niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh
niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand
niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)
niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody
niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails
niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON
niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken
niks3-go-unit-tests> === CONT  TestRateLimiterFeedback
niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter
niks3-go-unit-tests> === CONT  TestSetClientTLSErrors
niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport
niks3-go-unit-tests> === CONT  TestSetClientTLS
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=1 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Rate limiter enabled after throttle name=server-test rate=5
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=1 backoff=0s status=503 url=http://127.0.0.1:52851
niks3-go-unit-tests> === CONT  TestStreamPushStopsWhenCanceled
niks3-go-unit-tests> === CONT  TestClientSignaturesByStorePath
niks3-go-unit-tests> === CONT  TestFileTokenMissing
niks3-go-unit-tests> === CONT  TestStreamPushReportsSkippedPaths
niks3-go-unit-tests> === CONT  TestFileTokenEmpty
niks3-go-unit-tests> === CONT  TestStreamPushRequestLine
niks3-go-unit-tests> === CONT  TestStreamPushGivesUpOnDeadServer
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Rate limiter backed off name=server-test rate=5
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=2 backoff=0s status=503 url=http://127.0.0.1:52851
niks3-go-unit-tests> 2026/10/08 12:58:57 ERROR Upload failed error=boom count=1
niks3-go-unit-tests> === CONT  TestStreamPushIsolatesFailures
niks3-go-unit-tests> 2026/10/08 12:58:57 ERROR Upload failed error="connection refused" count=20
niks3-go-unit-tests> === CONT  TestStreamPushBatchesUnderLoad
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=2 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> 2026/10/08 12:58:57 ERROR Upload failed error="bad path" count=3
niks3-go-unit-tests> === CONT  TestStreamPushReportsEveryPath
niks3-go-unit-tests> === CONT  TestShellSplitErrors
niks3-go-unit-tests> === CONT  TestShellSplit
niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file
niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file
niks3-go-unit-tests> 2026/10/08 12:58:57 ERROR Server seems unavailable, giving up on batch untried=17
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=1 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> === CONT  TestDumpPathSingleFile
niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter
niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.00s)
niks3-go-unit-tests> === CONT  TestResolveStorePath
niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess
niks3-go-unit-tests> === CONT  TestStreamPushReportsSignatures
niks3-go-unit-tests> === CONT  TestFilterOversizedClosures
niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix
niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility
niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths
niks3-go-unit-tests> === CONT  TestParsePathInfoJSON
niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility
niks3-go-unit-tests> === CONT  TestGetStorePathHash
niks3-go-unit-tests> === CONT  TestConvertHashToNix32
niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix32
niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path
niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)
niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format
niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field
niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field
niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text
niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text
niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format
niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file
niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file
niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive
niks3-go-unit-tests> === RUN   TestFilterOversizedClosures/no_limit_keeps_everything
niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive
niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32
niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format
niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format
niks3-go-unit-tests> === CONT  TestEncodeNixBase32
niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash
niks3-go-unit-tests> === CONT  TestDumpPathWriterError
niks3-go-unit-tests> 2026/10/08 12:58:57 ERROR Upload failed error=boom count=1
niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches
niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter
niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)
niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format
niks3-go-unit-tests> === PAUSE TestFilterOversizedClosures/no_limit_keeps_everything
niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path
niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths
niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format
niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.00s)
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Rate limiter enabled after throttle name=server-test rate=5
niks3-go-unit-tests> --- PASS: TestStreamPushStopsWhenCanceled (0.00s)
niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file
niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter
niks3-go-unit-tests> --- PASS: TestClientSignaturesByStorePath (0.00s)
niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)
niks3-go-unit-tests> --- PASS: TestStreamPushReportsSkippedPaths (0.00s)
niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.02s)
niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.01s)
niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer
niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file
niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths
niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format
niks3-go-unit-tests> --- PASS: TestStreamPushIsolatesFailures (0.00s)
niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)
niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)
niks3-go-unit-tests> --- PASS: TestStreamPushReportsEveryPath (0.00s)
niks3-go-unit-tests> --- PASS: TestStreamPushRequestLine (0.01s)
niks3-go-unit-tests> --- PASS: TestStreamPushGivesUpOnDeadServer (0.01s)
niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.02s)
niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)
niks3-go-unit-tests> --- PASS: TestStreamPushReportsSignatures (0.00s)
niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter
niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter
niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter
niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter
niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error
niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error
niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error
niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error
niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error
niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error
niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text
niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text
niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method
niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method
niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash
niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash
niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.00s)
niks3-go-unit-tests> === RUN   TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped
niks3-go-unit-tests> === CONT  TestPartSizeForNAR
niks3-go-unit-tests> === PAUSE TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped
niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists
niks3-go-unit-tests> === RUN   TestFilterOversizedClosures/all_closures_skipped
niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file
niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input
niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths
niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths
niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists
niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing
niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing
niks3-go-unit-tests> === CONT  TestRunGarbageCollection_FinishedOnAnotherReplica
niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=2 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> === PAUSE TestFilterOversizedClosures/all_closures_skipped
niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field
niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method
niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input
niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input
niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only
niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only
niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON
niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON
niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text
niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error
niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error
niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter
niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input
niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon
niks3-go-unit-tests> === CONT  TestRunGarbageCollection_NotFoundAfterLocalRun
niks3-go-unit-tests> === CONT  TestUploadMultipart_PartsInParallel
niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file
niks3-go-unit-tests> === CONT  TestCaseHackSuffix
niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum
niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter
niks3-go-unit-tests> === CONT  TestRegisterUploadedObjectReusesConnections
niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert
niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.04s)
niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive
niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.04s)
niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path
niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths
niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists
niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text
niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.00s)
niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)
niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)
niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)
niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)
niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)
niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon
niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI
niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI
niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512
niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512
niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths
niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)
niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)
niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)
niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error
niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)
niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)
niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)
niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)
niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)
niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format
niks3-go-unit-tests> 2026/10/08 12:58:57 INFO Garbage collection started
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=3 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Rate limiter enabled after throttle name=server-test rate=5
niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum
niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum
niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum
niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum
niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum
niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts
niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=1 backoff=0s status=429 url=http://127.0.0.1:52864
niks3-go-unit-tests> 2026/10/08 12:58:57 INFO Garbage collection started
niks3-go-unit-tests> 2026/10/08 12:58:57 INFO Garbage collection progress phase=running_on_another_replica failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=0 objects_deleted=0 objects_failed=0
niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB
niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert
niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter
niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter
niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB
niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object
niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object
niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB
niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB
niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing
niks3-go-unit-tests> === CONT  TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped
niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32
niks3-go-unit-tests> === CONT  TestFilterOversizedClosures/no_limit_keeps_everything
niks3-go-unit-tests> === CONT  TestFilterOversizedClosures/all_closures_skipped
niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA
niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format
niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.06s)
niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format
niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA
niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport
niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport
niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON
niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file
niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format
niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.01s)
niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)
niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)
niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)
niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash
niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only
niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file
niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file
niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)
niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input
niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.01s)
niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)
niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Skipping closure: path exceeds server max NAR size top_level_path=/nix/store/cccccccccccccccccccccccccccccccc-wrapper oversized_path=/nix/store/bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb-vm-image nar_size=5000 max_nar_size=2000
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Rate limiter backed off name=server-test rate=5
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Garbage collection finished on another replica. Its statistics are not available here
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Skipping closure: path exceeds server max NAR size top_level_path=/nix/store/cccccccccccccccccccccccccccccccc-wrapper oversized_path=/nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-small nar_size=1000 max_nar_size=50
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Rate limiter enabled after throttle name=server-test rate=5
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=1 backoff=0s status=503 url=http://127.0.0.1:52939
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=4 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Rate limiter backed off name=server-test rate=5
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=5 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA
niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input
niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon
niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512
niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI
niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum
niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB
niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB
niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum
niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts
niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum
niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object
niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)
niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)
niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)
niks3-go-unit-tests> --- PASS: TestRunGarbageCollection_NotFoundAfterLocalRun (0.02s)
niks3-go-unit-tests> --- PASS: TestRunGarbageCollection_FinishedOnAnotherReplica (0.03s)
niks3-go-unit-tests> --- PASS: TestRegisterUploadedObjectReusesConnections (0.03s)
niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.08s)
niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.04s)
niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.01s)
niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.01s)
niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.02s)
niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.02s)
niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert
niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport
niks3-go-unit-tests> --- PASS: TestFilterOversizedClosures (0.01s)
niks3-go-unit-tests>     --- PASS: TestFilterOversizedClosures/no_limit_keeps_everything (0.00s)
niks3-go-unit-tests>     --- PASS: TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped (0.01s)
niks3-go-unit-tests>     --- PASS: TestFilterOversizedClosures/all_closures_skipped (0.01s)
niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.01s)
niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)
niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)
niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)
niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)
niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.02s)
niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)
niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)
niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)
niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)
niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)
niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)
niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)
niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.01s)
niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)
niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)
niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)
niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)
niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)
niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.08s)
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=6 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.05s)
niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)
niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)
niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)
niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=7 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=8 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> 2026/10/08 12:58:57 http: TLS handshake error from 127.0.0.1:52943: remote error: tls: bad certificate
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=9 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.05s)
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=10 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=11 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.05s)
niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)
niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)
niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=12 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=13 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.05s)
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=14 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=15 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=16 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> 2026/10/08 12:58:57 WARN Request returned retryable status, retrying attempt=17 backoff=1ms status=502 url=http://127.0.0.1:52848
niks3-go-unit-tests> --- PASS: TestRetryTimeoutOutlastsMaxRetries (0.12s)
niks3-go-unit-tests> --- PASS: TestStreamPushBatchesUnderLoad (0.10s)
niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.25s)
niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.25s)
niks3-go-unit-tests> --- PASS: TestUploadMultipart_PartsInParallel (0.61s)
niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)
niks3-go-unit-tests> PASS
niks3-go-unit-tests> Running server tests...
niks3-go-unit-tests> The files belonging to this database system will be owned by user "_tribuchetbld8".
niks3-go-unit-tests> This user must also own the server process.
niks3-go-unit-tests> 
niks3-go-unit-tests> The database cluster will be initialized with locale "C".
niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".
niks3-go-unit-tests> The default text search configuration will be set to "english".
niks3-go-unit-tests> 
niks3-go-unit-tests> Data page checksums are enabled.
niks3-go-unit-tests> 
niks3-go-unit-tests> creating directory /private/var/tb/8/scratch/f058402f878f26dd/build/postgres1865263893/data ... ok
niks3-go-unit-tests> creating subdirectories ... ok
niks3-go-unit-tests> selecting dynamic shared memory implementation ... posix
niks3-go-unit-tests> selecting default "max_connections" ... 100
niks3-go-unit-tests> selecting default "shared_buffers" ... 128MB
niks3-go-unit-tests> selecting default time zone ... UTC
niks3-go-unit-tests> creating configuration files ... ok
niks3-go-unit-tests> running bootstrap script ... ok
niks3-go-unit-tests> performing post-bootstrap initialization ... ok
niks3-go-unit-tests> syncing data to disk ... ok
niks3-go-unit-tests> 
niks3-go-unit-tests> initdb: warning: enabling "trust" authentication for local connections
niks3-go-unit-tests> initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.
niks3-go-unit-tests> 
niks3-go-unit-tests> Success. You can now start the database server using:
niks3-go-unit-tests> 
niks3-go-unit-tests>     pg_ctl -D /private/var/tb/8/scratch/f058402f878f26dd/build/postgres1865263893/data -l logfile start
niks3-go-unit-tests> 
niks3-go-unit-tests> /private/var/tb/8/scratch/f058402f878f26dd/build/postgres1865263893:5432 - no response
niks3-go-unit-tests> 2026-10-08 12:59:06.478 UTC [45012] LOG:  starting PostgreSQL 18.6 on aarch64-apple-darwin25.6.0, compiled by clang version 21.1.8, 64-bit
niks3-go-unit-tests> 2026-10-08 12:59:06.494 UTC [45012] LOG:  listening on Unix socket "/private/var/tb/8/scratch/f058402f878f26dd/build/postgres1865263893/.s.PGSQL.5432"
niks3-go-unit-tests> 2026-10-08 12:59:06.505 UTC [45019] LOG:  database system was shut down at 2026-10-08 12:59:06 UTC
niks3-go-unit-tests> 2026-10-08 12:59:06.515 UTC [45012] LOG:  database system is ready to accept connections
niks3-go-unit-tests> /private/var/tb/8/scratch/f058402f878f26dd/build/postgres1865263893:5432 - accepting connections
niks3-go-unit-tests> {"timestamp":"2026-10-08T12:59:06.714209Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"20761211-f1a8-49a8-81a2-c35256d7b0d8","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(4)"}
niks3-go-unit-tests> {"timestamp":"2026-10-08T12:59:06.819129Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"fdee4737-b068-437b-a700-c2f9e335724d","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(3)"}
niks3-go-unit-tests> {"timestamp":"2026-10-08T12:59:06.922951Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"d46c6db6-13a9-41eb-93df-1f9d4bcfbcdf","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(4)"}
niks3-go-unit-tests> {"timestamp":"2026-10-08T12:59:07.026921Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"86f5ebe6-383f-4c7a-9531-ce9a0532f00a","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(6)"}
niks3-go-unit-tests> === RUN   TestService_AuthMiddleware
niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware
niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader
niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader
niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects
niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects
niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware
niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware
niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC
niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC
niks3-go-unit-tests> === RUN   TestService_ReadScope_PublicByDefault
niks3-go-unit-tests> === PAUSE TestService_ReadScope_PublicByDefault
niks3-go-unit-tests> === RUN   TestCacheConfigHandler
niks3-go-unit-tests> === PAUSE TestCacheConfigHandler
niks3-go-unit-tests> === RUN   TestCacheStatsHandler
niks3-go-unit-tests> === PAUSE TestCacheStatsHandler
niks3-go-unit-tests> === RUN   TestClientCADerivations
niks3-go-unit-tests> === PAUSE TestClientCADerivations
niks3-go-unit-tests> === RUN   TestClientErrorHandling
niks3-go-unit-tests> === PAUSE TestClientErrorHandling
niks3-go-unit-tests> === RUN   TestClientIntegration
niks3-go-unit-tests> === PAUSE TestClientIntegration
niks3-go-unit-tests> === RUN   TestClientMultipleUploads
niks3-go-unit-tests> === PAUSE TestClientMultipleUploads
niks3-go-unit-tests> === RUN   TestClientWithDependencies
niks3-go-unit-tests> === PAUSE TestClientWithDependencies
niks3-go-unit-tests> === RUN   TestClientSharedPathCommittedMidPush
niks3-go-unit-tests> === PAUSE TestClientSharedPathCommittedMidPush
niks3-go-unit-tests> === RUN   TestPinProtectsFromGC
niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC
niks3-go-unit-tests> === RUN   TestClientPushesUseOnePush
niks3-go-unit-tests> === PAUSE TestClientPushesUseOnePush
niks3-go-unit-tests> === RUN   TestClientFallsBackToClosures
niks3-go-unit-tests> === PAUSE TestClientFallsBackToClosures
niks3-go-unit-tests> === RUN   TestDeleteClosuresRacingPins
niks3-go-unit-tests> === PAUSE TestDeleteClosuresRacingPins
niks3-go-unit-tests> === RUN   TestConcurrentCommitsSharingObjectsDoNotDeadlock
niks3-go-unit-tests> === PAUSE TestConcurrentCommitsSharingObjectsDoNotDeadlock
niks3-go-unit-tests> === RUN   TestResolveDBConnectionString
niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString
niks3-go-unit-tests> === RUN   TestConnectWaitsForAPeerMigration
niks3-go-unit-tests> === PAUSE TestConnectWaitsForAPeerMigration
niks3-go-unit-tests> === RUN   TestLeadElectsOneAndHandsOver
niks3-go-unit-tests> === PAUSE TestLeadElectsOneAndHandsOver
niks3-go-unit-tests> === RUN   TestLeadIncumbentWinsAfterRestart
niks3-go-unit-tests> 2026/10/08 12:59:10 INFO lead: acquired remote=192.0.2.1:1234
niks3-go-unit-tests> 2026/10/08 12:59:11 INFO lead: released remote=192.0.2.1:1234
niks3-go-unit-tests> 2026/10/08 12:59:11 INFO lead: acquired remote=192.0.2.1:1234
niks3-go-unit-tests> 2026/10/08 12:59:12 INFO lead: released remote=192.0.2.1:1234
niks3-go-unit-tests> --- PASS: TestLeadIncumbentWinsAfterRestart (5.13s)
niks3-go-unit-tests> === RUN   TestLeadIncumbentAnnouncesAtOnce
niks3-go-unit-tests> === PAUSE TestLeadIncumbentAnnouncesAtOnce
niks3-go-unit-tests> === RUN   TestLeadEndsOnShutdown
niks3-go-unit-tests> === PAUSE TestLeadEndsOnShutdown
niks3-go-unit-tests> === RUN   TestLeadEndsWhenItsConnectionHangs
niks3-go-unit-tests> 2026/10/08 12:59:14 INFO lead: acquired remote=192.0.2.1:1234
niks3-go-unit-tests> 2026-10-08 12:59:16.039 UTC [45114] FATAL:  terminating connection due to administrator command
niks3-go-unit-tests> 2026/10/08 12:59:16 INFO lead: acquired remote=192.0.2.1:1234
niks3-go-unit-tests> 2026/10/08 12:59:17 WARN lead: lock connection lost error="timeout: context deadline exceeded"
niks3-go-unit-tests> 2026/10/08 12:59:17 INFO lead: released remote=192.0.2.1:1234
niks3-go-unit-tests> 2026/10/08 12:59:17 INFO lead: released remote=192.0.2.1:1234
niks3-go-unit-tests> --- PASS: TestLeadEndsWhenItsConnectionHangs (5.15s)
niks3-go-unit-tests> === RUN   TestGCAdvisoryLockBlocksConcurrentRun
niks3-go-unit-tests> === PAUSE TestGCAdvisoryLockBlocksConcurrentRun
niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences
niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences
niks3-go-unit-tests> === RUN   TestGCMetrics
niks3-go-unit-tests> === PAUSE TestGCMetrics
niks3-go-unit-tests> === RUN   TestPushDedupSurvivesConcurrentGC
niks3-go-unit-tests> === PAUSE TestPushDedupSurvivesConcurrentGC
niks3-go-unit-tests> === RUN   TestDeduplicatedObjectsRecordedAsPending
niks3-go-unit-tests> === PAUSE TestDeduplicatedObjectsRecordedAsPending
niks3-go-unit-tests> === RUN   TestGCSweepSkipsPendingObjects
niks3-go-unit-tests> === PAUSE TestGCSweepSkipsPendingObjects
niks3-go-unit-tests> === RUN   TestTombstonedObjectOfferedWithoutWaiting
niks3-go-unit-tests> === PAUSE TestTombstonedObjectOfferedWithoutWaiting
niks3-go-unit-tests> === RUN   TestGCSweepDeliversEachKeyOnce
niks3-go-unit-tests> === PAUSE TestGCSweepDeliversEachKeyOnce
niks3-go-unit-tests> === RUN   TestCreatePendingClosureVerifyS3FailureReleasesConnection
niks3-go-unit-tests> === PAUSE TestCreatePendingClosureVerifyS3FailureReleasesConnection
niks3-go-unit-tests> === RUN   TestForceGCDuringPushOffersSweptObject
niks3-go-unit-tests> === PAUSE TestForceGCDuringPushOffersSweptObject
niks3-go-unit-tests> === RUN   TestFailedS3DeleteKeepsTombstone
niks3-go-unit-tests> === PAUSE TestFailedS3DeleteKeepsTombstone
niks3-go-unit-tests> === RUN   TestSweepSkipsRowsLockedByPush
niks3-go-unit-tests> === PAUSE TestSweepSkipsRowsLockedByPush
niks3-go-unit-tests> === RUN   TestRegisterCompletedObjectNeedsPendingRow
niks3-go-unit-tests> === PAUSE TestRegisterCompletedObjectNeedsPendingRow
niks3-go-unit-tests> === RUN   TestGetExistingObjectsLocksRows
niks3-go-unit-tests> === PAUSE TestGetExistingObjectsLocksRows
niks3-go-unit-tests> === RUN   TestSweepRechecksPendingAfterLocking
niks3-go-unit-tests> === PAUSE TestSweepRechecksPendingAfterLocking
niks3-go-unit-tests> === RUN   TestPushWaitsForSweepHoldingRow
niks3-go-unit-tests> === PAUSE TestPushWaitsForSweepHoldingRow
niks3-go-unit-tests> === RUN   TestCommitRacingPendingCleanupKeepsObjects
niks3-go-unit-tests> === PAUSE TestCommitRacingPendingCleanupKeepsObjects
niks3-go-unit-tests> === RUN   TestGCEndsOnShutdown
niks3-go-unit-tests> === PAUSE TestGCEndsOnShutdown
niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew
niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew
niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams
niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams
niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams
niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams
niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty
niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty
niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest
niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest
niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask
niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask
niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates
niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates
niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail
niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail
niks3-go-unit-tests> === RUN   TestGracefulShutdownDrainsInflight
niks3-go-unit-tests> === PAUSE TestGracefulShutdownDrainsInflight
niks3-go-unit-tests> === RUN   TestService_healthCheckHandler
niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler
niks3-go-unit-tests> === RUN   TestService_readinessHandler
niks3-go-unit-tests> === PAUSE TestService_readinessHandler
niks3-go-unit-tests> === RUN   TestGenerateLandingPage
niks3-go-unit-tests> === PAUSE TestGenerateLandingPage
niks3-go-unit-tests> === RUN   TestCacheConfigHandlerMaxNarSize
niks3-go-unit-tests> === PAUSE TestCacheConfigHandlerMaxNarSize
niks3-go-unit-tests> === RUN   TestCreatePendingClosureRejectsOversizedNAR
niks3-go-unit-tests> === PAUSE TestCreatePendingClosureRejectsOversizedNAR
niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug
niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug
niks3-go-unit-tests> === RUN   TestMetricsInventory
niks3-go-unit-tests> === PAUSE TestMetricsInventory
niks3-go-unit-tests> === RUN   TestMetricsCommit
niks3-go-unit-tests> === PAUSE TestMetricsCommit
niks3-go-unit-tests> === RUN   TestService_NativeMTLS
niks3-go-unit-tests> === PAUSE TestService_NativeMTLS
niks3-go-unit-tests> === RUN   TestServerTLSConfig
niks3-go-unit-tests> === PAUSE TestServerTLSConfig
niks3-go-unit-tests> === RUN   TestMultipartCleanup
niks3-go-unit-tests> === PAUSE TestMultipartCleanup
niks3-go-unit-tests> === RUN   TestMultipartUploadAbortedWhenCancelledBeforeRecorded
niks3-go-unit-tests> === PAUSE TestMultipartUploadAbortedWhenCancelledBeforeRecorded
niks3-go-unit-tests> === RUN   TestPendingCleanupUsesOneCutoff
niks3-go-unit-tests> === PAUSE TestPendingCleanupUsesOneCutoff
niks3-go-unit-tests> === RUN   TestObjectStatsTrigger
niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger
niks3-go-unit-tests> === RUN   TestObjectStatsLargeCommit
niks3-go-unit-tests> === PAUSE TestObjectStatsLargeCommit
niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC
niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC
niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest
niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest
niks3-go-unit-tests> === RUN   TestCreatePin_ReservedPins
niks3-go-unit-tests> === PAUSE TestCreatePin_ReservedPins
niks3-go-unit-tests> === RUN   TestPresentReportsOnlyClosureRoots
niks3-go-unit-tests> === PAUSE TestPresentReportsOnlyClosureRoots
niks3-go-unit-tests> === RUN   TestPresentRefreshesOnlyStaleClosures
niks3-go-unit-tests> === PAUSE TestPresentRefreshesOnlyStaleClosures
niks3-go-unit-tests> === RUN   TestPresentNotReportedWhileGCDeletesClosure
niks3-go-unit-tests> === PAUSE TestPresentNotReportedWhileGCDeletesClosure
niks3-go-unit-tests> === RUN   TestParseSingleRange
niks3-go-unit-tests> === PAUSE TestParseSingleRange
niks3-go-unit-tests> === RUN   TestProxyHeadersOnlyTrustedOnSocket
niks3-go-unit-tests> 2026/10/08 12:59:17 INFO Starting HTTP server address=127.0.0.1:53255
niks3-go-unit-tests> 2026/10/08 12:59:17 INFO Starting HTTP server address=proxy.sock
niks3-go-unit-tests> 2026/10/08 12:59:17 WARN mTLS auth: subject not in bound subjects subject="CN=someone"
niks3-go-unit-tests> 2026/10/08 12:59:17 INFO Shutdown signal received, draining in-flight requests timeout=10s
niks3-go-unit-tests> --- PASS: TestProxyHeadersOnlyTrustedOnSocket (0.33s)
niks3-go-unit-tests> === RUN   TestIsValidCachePath
niks3-go-unit-tests> === PAUSE TestIsValidCachePath
niks3-go-unit-tests> === RUN   TestReadProxyNarinfo
niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo
niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed
niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed
niks3-go-unit-tests> === RUN   TestReadProxyContentNegotiation
niks3-go-unit-tests> === PAUSE TestReadProxyContentNegotiation
niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming
niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming
niks3-go-unit-tests> === RUN   TestReadProxy404
niks3-go-unit-tests> === PAUSE TestReadProxy404
niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath
niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath
niks3-go-unit-tests> === RUN   TestReadProxyHead
niks3-go-unit-tests> === PAUSE TestReadProxyHead
niks3-go-unit-tests> === RUN   TestReadProxyOutlastsServerWriteTimeout
niks3-go-unit-tests> === PAUSE TestReadProxyOutlastsServerWriteTimeout
niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet
niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet
niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML
niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML
niks3-go-unit-tests> === RUN   TestReadProxyDisabled
niks3-go-unit-tests> === PAUSE TestReadProxyDisabled
niks3-go-unit-tests> === RUN   TestReadRedirectNar
niks3-go-unit-tests> === PAUSE TestReadRedirectNar
niks3-go-unit-tests> === RUN   TestReadRedirectKeepsNarinfoProxied
niks3-go-unit-tests> === PAUSE TestReadRedirectKeepsNarinfoProxied
niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest
niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest
niks3-go-unit-tests> === RUN   TestReadRedirectUsesPublicS3URL
niks3-go-unit-tests> === PAUSE TestReadRedirectUsesPublicS3URL
niks3-go-unit-tests> === RUN   TestPush_OverlappingRootsStoreOneRowPerKey
niks3-go-unit-tests> === PAUSE TestPush_OverlappingRootsStoreOneRowPerKey
niks3-go-unit-tests> === RUN   TestPush_CompleteCommitsEveryRoot
niks3-go-unit-tests> === PAUSE TestPush_CompleteCommitsEveryRoot
niks3-go-unit-tests> === RUN   TestPush_RepeatedRootCommitsOnce
niks3-go-unit-tests> === PAUSE TestPush_RepeatedRootCommitsOnce
niks3-go-unit-tests> === RUN   TestPush_CompleteWaitsForCleanupOfThePush
niks3-go-unit-tests> === PAUSE TestPush_CompleteWaitsForCleanupOfThePush
niks3-go-unit-tests> === RUN   TestPush_RootsAreLockedInByteOrder
niks3-go-unit-tests> === PAUSE TestPush_RootsAreLockedInByteOrder
niks3-go-unit-tests> === RUN   TestPush_SkippedKeySurvivesGCBeforeCommit
niks3-go-unit-tests> === PAUSE TestPush_SkippedKeySurvivesGCBeforeCommit
niks3-go-unit-tests> === RUN   TestPush_RejectsBadRequests
niks3-go-unit-tests> === PAUSE TestPush_RejectsBadRequests
niks3-go-unit-tests> === RUN   TestPush_SignsNarinfosOfItsPendingObjects
niks3-go-unit-tests> === PAUSE TestPush_SignsNarinfosOfItsPendingObjects
niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload
niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload
niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists
niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists
niks3-go-unit-tests> === RUN   TestCompletedNarNotReofferedAcrossClosures
niks3-go-unit-tests> === PAUSE TestCompletedNarNotReofferedAcrossClosures
niks3-go-unit-tests> === RUN   TestPresignedUploadRegisteredBeforeCommit
niks3-go-unit-tests> === PAUSE TestPresignedUploadRegisteredBeforeCommit
niks3-go-unit-tests> === RUN   TestService_Rustfstest
niks3-go-unit-tests> === PAUSE TestService_Rustfstest
niks3-go-unit-tests> === RUN   TestParseSize
niks3-go-unit-tests> === PAUSE TestParseSize
niks3-go-unit-tests> === RUN   TestSkippedUploadsHandler
niks3-go-unit-tests> === PAUSE TestSkippedUploadsHandler
niks3-go-unit-tests> === RUN   TestSystemdListenerNotActivated
niks3-go-unit-tests> --- PASS: TestSystemdListenerNotActivated (0.00s)
niks3-go-unit-tests> === RUN   TestWatchdogBeatsWhenHealthy
niks3-go-unit-tests> --- PASS: TestWatchdogBeatsWhenHealthy (0.03s)
niks3-go-unit-tests> === RUN   TestWatchdogSkipsWhenUnhealthy
niks3-go-unit-tests> 2026/10/08 12:59:17 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
niks3-go-unit-tests> 2026/10/08 12:59:17 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
niks3-go-unit-tests> 2026/10/08 12:59:17 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
niks3-go-unit-tests> 2026/10/08 12:59:17 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
niks3-go-unit-tests> 2026/10/08 12:59:17 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
niks3-go-unit-tests> 2026/10/08 12:59:17 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
niks3-go-unit-tests> 2026/10/08 12:59:17 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
niks3-go-unit-tests> 2026/10/08 12:59:17 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
niks3-go-unit-tests> 2026/10/08 12:59:17 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
niks3-go-unit-tests> 2026/10/08 12:59:18 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
niks3-go-unit-tests> --- PASS: TestWatchdogSkipsWhenUnhealthy (0.21s)
niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle
niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle
niks3-go-unit-tests> === RUN   TestProxyWriteTimeout
niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout
niks3-go-unit-tests> === RUN   TestPendingClosureWriteTimeout
niks3-go-unit-tests> === PAUSE TestPendingClosureWriteTimeout
niks3-go-unit-tests> === RUN   TestIsValidUploadKey
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey
niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys
niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys
niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody
niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody
niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler
niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler
niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler
niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler
niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity
niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity
niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered
niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered
niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT
niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT
niks3-go-unit-tests> === CONT  TestParseSize
niks3-go-unit-tests> === CONT  TestPush_RejectsBadRequests
niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask
niks3-go-unit-tests> === CONT  TestSkippedUploadsHandler
niks3-go-unit-tests> --- PASS: TestParseSize (0.00s)
niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)
niks3-go-unit-tests> === CONT  TestPresentNotReportedWhileGCDeletesClosure
niks3-go-unit-tests> === CONT  TestService_Rustfstest
niks3-go-unit-tests> === RUN   TestPresentNotReportedWhileGCDeletesClosure/stale
niks3-go-unit-tests> === PAUSE TestPresentNotReportedWhileGCDeletesClosure/stale
niks3-go-unit-tests> === RUN   TestPresentNotReportedWhileGCDeletesClosure/fresh
niks3-go-unit-tests> === PAUSE TestPresentNotReportedWhileGCDeletesClosure/fresh
niks3-go-unit-tests> === CONT  TestPresentRefreshesOnlyStaleClosures
niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest
niks3-go-unit-tests> === CONT  TestPresignedUploadRegisteredBeforeCommit
niks3-go-unit-tests> === CONT  TestCompletedNarNotReofferedAcrossClosures
niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists
niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody
niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload
niks3-go-unit-tests> === CONT  TestPush_SignsNarinfosOfItsPendingObjects
niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered
niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity
niks3-go-unit-tests> 2026/10/08 12:59:18 INFO Client skipped oversized paths paths=3 nar_bytes=5000000000
niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler
niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler
niks3-go-unit-tests> === CONT  TestService_AuthMiddleware
niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys
niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info
niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info
niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal
niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal
niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key
niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key
niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key
niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key
niks3-go-unit-tests> === CONT  TestIsValidUploadKey
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain
niks3-go-unit-tests> === CONT  TestPendingClosureWriteTimeout
niks3-go-unit-tests> === CONT  TestProxyWriteTimeout
niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle
niks3-go-unit-tests> === CONT  TestPresentReportsOnlyClosureRoots
niks3-go-unit-tests> === CONT  TestCreatePin_ReservedPins
niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT
niks3-go-unit-tests> --- PASS: TestSkippedUploadsHandler (0.03s)
niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing
niks3-go-unit-tests> === RUN   TestPendingClosureWriteTimeout/empty
niks3-go-unit-tests> === PAUSE TestPendingClosureWriteTimeout/empty
niks3-go-unit-tests> === RUN   TestPendingClosureWriteTimeout/400_objects
niks3-go-unit-tests> === PAUSE TestPendingClosureWriteTimeout/400_objects
niks3-go-unit-tests> === RUN   TestPendingClosureWriteTimeout/670k_objects
niks3-go-unit-tests> === PAUSE TestPendingClosureWriteTimeout/670k_objects
niks3-go-unit-tests> === RUN   TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline
niks3-go-unit-tests> === PAUSE TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline
niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo
niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo
niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar
niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar
niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar
niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar
niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size
niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size
niks3-go-unit-tests> === CONT  TestObjectStatsTrigger
niks3-go-unit-tests> === CONT  TestObjectStatsLargeCommit
niks3-go-unit-tests> === CONT  TestPendingCleanupUsesOneCutoff
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key
niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type
niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type
niks3-go-unit-tests> === CONT  TestMultipartUploadAbortedWhenCancelledBeforeRecorded
niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure
niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure
niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart
niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart
niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts
niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts
niks3-go-unit-tests> === CONT  TestMultipartCleanup
niks3-go-unit-tests> 2026/10/08 12:59:18 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53260/oidc
niks3-go-unit-tests> 2026/10/08 12:59:19 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:19 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:19 INFO Signed narinfos id=1 count=1
niks3-go-unit-tests> 2026/10/08 12:59:19 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:19 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:19 ERROR Failed to sign narinfos signer="index 1" error="signing key cannot be nil"
niks3-go-unit-tests> 2026/10/08 12:59:19 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:19 ERROR Failed to sign narinfos signer=niks3-test-1 error="signer failed"
niks3-go-unit-tests> 2026/10/08 12:59:19 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:19 ERROR Signer returned unexpected signature count signer=niks3-test-1 got=0 want=1
niks3-go-unit-tests> 2026/10/08 12:59:19 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:19 ERROR Signer returned unexpected object key signer=niks3-test-1 object_key=unexpected.narinfo
niks3-go-unit-tests> --- PASS: TestPush_SignsNarinfosOfItsPendingObjects (1.84s)
niks3-go-unit-tests> === CONT  TestServerTLSConfig
niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA
niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA
niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file
niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file
niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file
niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file
niks3-go-unit-tests> === CONT  TestService_NativeMTLS
niks3-go-unit-tests> 2026/10/08 12:59:20 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:20 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:20 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> === RUN   TestPush_RejectsBadRequests/no_roots
niks3-go-unit-tests> === PAUSE TestPush_RejectsBadRequests/no_roots
niks3-go-unit-tests> === RUN   TestPush_RejectsBadRequests/no_objects
niks3-go-unit-tests> === PAUSE TestPush_RejectsBadRequests/no_objects
niks3-go-unit-tests> === RUN   TestPush_RejectsBadRequests/bad_root
niks3-go-unit-tests> === PAUSE TestPush_RejectsBadRequests/bad_root
niks3-go-unit-tests> === RUN   TestPush_RejectsBadRequests/root_not_in_objects
niks3-go-unit-tests> === PAUSE TestPush_RejectsBadRequests/root_not_in_objects
niks3-go-unit-tests> === CONT  TestMetricsCommit
niks3-go-unit-tests> 2026/10/08 12:59:21 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:21 INFO Registered completed upload object_key=nar/jjjjjjjjjjjjjjjjjjjjjjjjjjjjjj0100000000000000000000.nar.zst
niks3-go-unit-tests> 2026/10/08 12:59:21 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> --- PASS: TestPresignedUploadRegisteredBeforeCommit (3.39s)
niks3-go-unit-tests> === CONT  TestMetricsInventory
niks3-go-unit-tests> --- PASS: TestService_Rustfstest (3.70s)
niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=ZDUzYjQ1ZmUtMDk3Yy00YjM2LTliOGEtMWE3OTFkNzM1M2Q4LmU3NWM4OGExLWQ4YzEtNGM3MC1iYzAyLTU0YzAxMGUyNWJmOXgxNzkxNDY0MzYwMjUwMTEzMDAw parts=10
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Completed upload id=1 objects=2 duration=17.219833ms
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Starting cleanup of old closures method=DELETE path=/api/closures
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Vacuumed table table=objects
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
niks3-go-unit-tests> 2026/10/08 12:59:22 WARN CompleteMultipartUpload errored but object exists; treating as success error="The specified key does not exist." object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=ZDUzYjQ1ZmUtMDk3Yy00YjM2LTliOGEtMWE3OTFkNzM1M2Q4LmQ5MjA4OWZkLWUwMWYtNDA5NC05NDBiLTk3MzdkYjBhYmMyYngxNzkxNDY0MzYyNTMyNDQyMDAw
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000
niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (4.84s)
niks3-go-unit-tests> === CONT  TestCreatePendingClosureRejectsOversizedNAR
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> --- PASS: TestCreatePendingClosureRejectsOversizedNAR (0.00s)
niks3-go-unit-tests> === CONT  TestCacheConfigHandlerMaxNarSize
niks3-go-unit-tests> --- PASS: TestCacheConfigHandlerMaxNarSize (0.00s)
niks3-go-unit-tests> === CONT  TestGenerateLandingPage
niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)
niks3-go-unit-tests> === CONT  TestService_readinessHandler
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=ZDUzYjQ1ZmUtMDk3Yy00YjM2LTliOGEtMWE3OTFkNzM1M2Q4LmQ5MjA4OWZkLWUwMWYtNDA5NC05NDBiLTk3MzdkYjBhYmMyYngxNzkxNDY0MzYyNTMyNDQyMDAw parts=1
niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (4.88s)
niks3-go-unit-tests> === CONT  TestService_healthCheckHandler
niks3-go-unit-tests> --- PASS: TestPresentRefreshesOnlyStaleClosures (4.92s)
niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Starting HTTP server address=127.0.0.1:53332
niks3-go-unit-tests> 2026/10/08 12:59:22 INFO Shutdown signal received, draining in-flight requests timeout=10s
niks3-go-unit-tests> --- PASS: TestGracefulShutdownDrainsInflight (0.07s)
niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail
niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)
niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates
niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)
niks3-go-unit-tests> === CONT  TestLeadEndsOnShutdown
niks3-go-unit-tests> 2026/10/08 12:59:23 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:23 INFO Received cleanup request method=DELETE path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:23 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:23 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:23 INFO Received cleanup request method=DELETE path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:23 INFO Aborted multipart uploads count=1 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:23 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
niks3-go-unit-tests> 2026-10-08 12:59:23.959 UTC [45204] ERROR:  Closure does not exist: id=1
niks3-go-unit-tests> 2026-10-08 12:59:23.959 UTC [45204] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 13 at RAISE
niks3-go-unit-tests> 2026-10-08 12:59:23.959 UTC [45204] STATEMENT:  -- name: CommitPendingClosure :one
niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)::bigint
niks3-go-unit-tests> 	
niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (5.92s)
niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest
niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)
niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty
niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)
niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams
niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)
niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams
niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)
niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew
niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)
niks3-go-unit-tests> === CONT  TestGCEndsOnShutdown
niks3-go-unit-tests> === RUN   TestGCEndsOnShutdown/before_the_run
niks3-go-unit-tests> === PAUSE TestGCEndsOnShutdown/before_the_run
niks3-go-unit-tests> === RUN   TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete
niks3-go-unit-tests> === PAUSE TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete
niks3-go-unit-tests> === RUN   TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark
niks3-go-unit-tests> === PAUSE TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark
niks3-go-unit-tests> === CONT  TestCommitRacingPendingCleanupKeepsObjects
niks3-go-unit-tests> 2026/10/08 12:59:24 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:24 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
niks3-go-unit-tests> 2026/10/08 12:59:25 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (7.24s)
niks3-go-unit-tests> === CONT  TestPushWaitsForSweepHoldingRow
niks3-go-unit-tests> 2026/10/08 12:59:25 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"
niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (7.61s)
niks3-go-unit-tests> === CONT  TestSweepRechecksPendingAfterLocking
niks3-go-unit-tests> 2026/10/08 12:59:25 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
niks3-go-unit-tests> 2026/10/08 12:59:25 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=ZDUzYjQ1ZmUtMDk3Yy00YjM2LTliOGEtMWE3OTFkNzM1M2Q4Ljc3ZGI1YWU1LTRlYTktNGNhZi1iNmE3LWI0ZGI5M2M5MWMyYXgxNzkxNDY0MzYzMzU1NjAyMDAw parts=10
niks3-go-unit-tests> 2026/10/08 12:59:25 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:25 INFO Completed upload id=1 objects=2 duration=21.959ms
niks3-go-unit-tests> 2026/10/08 12:59:25 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:25 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:25 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo
niks3-go-unit-tests> 2026/10/08 12:59:25 WARN Found objects in DB but missing from S3, will re-upload count=1
niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (7.86s)
niks3-go-unit-tests> === CONT  TestGetExistingObjectsLocksRows
niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (8.16s)
niks3-go-unit-tests> === CONT  TestRegisterCompletedObjectNeedsPendingRow
niks3-go-unit-tests> 2026/10/08 12:59:26 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:27 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:27 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:27 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:27 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:27 INFO Received cleanup request method=DELETE path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:28 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
niks3-go-unit-tests> 2026/10/08 12:59:28 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst
niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (10.17s)
niks3-go-unit-tests> === CONT  TestSweepSkipsRowsLockedByPush
niks3-go-unit-tests> --- PASS: TestPresentReportsOnlyClosureRoots (10.84s)
niks3-go-unit-tests> === CONT  TestFailedS3DeleteKeepsTombstone
niks3-go-unit-tests> 2026/10/08 12:59:28 WARN Rate limiter enabled after throttle name=s3-test rate=5
niks3-go-unit-tests> 2026/10/08 12:59:28 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."
niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle
niks3-go-unit-tests>     throttle_test.go:213: Proxy stats: total=13, throttled=10, completeMultipart=10
niks3-go-unit-tests>     throttle_test.go:215: Rate limiter: enabled=true, rate=5.00
niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (10.91s)
niks3-go-unit-tests> === CONT  TestForceGCDuringPushOffersSweptObject
niks3-go-unit-tests> === RUN   TestForceGCDuringPushOffersSweptObject/before_pending_rows
niks3-go-unit-tests> === PAUSE TestForceGCDuringPushOffersSweptObject/before_pending_rows
niks3-go-unit-tests> === RUN   TestForceGCDuringPushOffersSweptObject/after_presence_check
niks3-go-unit-tests> === PAUSE TestForceGCDuringPushOffersSweptObject/after_presence_check
niks3-go-unit-tests> === CONT  TestCreatePendingClosureVerifyS3FailureReleasesConnection
niks3-go-unit-tests> 2026/10/08 12:59:29 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
niks3-go-unit-tests> 2026/10/08 12:59:29 INFO Completed multipart upload object_key=nar/hhhhhhhhhhhhhhhhhhhhhhhhhhhhhh0100000000000000000000.nar.zst upload_id=ZDUzYjQ1ZmUtMDk3Yy00YjM2LTliOGEtMWE3OTFkNzM1M2Q4LmM0OTAzMzU3LWU4YmYtNDhiOS04NzQwLWUwNTZiM2RhZWExZHgxNzkxNDY0MzY2NzQ2MjkxMDAw parts=12
niks3-go-unit-tests> 2026/10/08 12:59:29 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> --- PASS: TestCompletedNarNotReofferedAcrossClosures (11.78s)
niks3-go-unit-tests> === CONT  TestGCSweepDeliversEachKeyOnce
niks3-go-unit-tests> 2026/10/08 12:59:29 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> --- PASS: TestMultipartUploadAbortedWhenCancelledBeforeRecorded (11.86s)
niks3-go-unit-tests> === CONT  TestTombstonedObjectOfferedWithoutWaiting
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=ZDUzYjQ1ZmUtMDk3Yy00YjM2LTliOGEtMWE3OTFkNzM1M2Q4LjM4YjQ1NTZmLTk2MDgtNDYwYi1iOTlhLTlhZjU5ZTc5ODI5YXgxNzkxNDY0MzY3MjUwMDE5MDAw parts=12
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Received cleanup request method=DELETE path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:30 WARN Failed to abort upload, keeping its closure key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst error="Get \"http://127.0.0.1:1/bucket26/?location=\": dial tcp 127.0.0.1:1: connect: connection refused" code=""
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Aborted multipart uploads count=0 kept=1
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Received cleanup request method=DELETE path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Aborted multipart uploads count=1 kept=0
niks3-go-unit-tests> --- PASS: TestMultipartCleanup (12.53s)
niks3-go-unit-tests> === CONT  TestGCSweepSkipsPendingObjects
niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC
niks3-go-unit-tests>     orphaned_objects_gc_test.go:287: GC Test Summary:
niks3-go-unit-tests>     orphaned_objects_gc_test.go:288:   - Kept:    2 objects from closure A
niks3-go-unit-tests>     orphaned_objects_gc_test.go:289:   - Deleted: 2 objects from closure B
niks3-go-unit-tests>     orphaned_objects_gc_test.go:290:   - Deleted: 6 orphaned chain objects (X1->X2->X3)
niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Deleted: 2 orphaned single objects (Y)
niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Total deleted: 10 objects
niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (12.66s)
niks3-go-unit-tests> === CONT  TestDeduplicatedObjectsRecordedAsPending
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux
niks3-go-unit-tests> 2026/10/08 12:59:30 WARN Refused reserved pin name=worker-x86_64-linux
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Received create pin request method=POST path=/api/pins/my-app
niks3-go-unit-tests> 2026/10/08 12:59:30 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux
niks3-go-unit-tests> --- PASS: TestCreatePin_ReservedPins (12.77s)
niks3-go-unit-tests> === CONT  TestPushDedupSurvivesConcurrentGC
niks3-go-unit-tests> --- PASS: TestMetricsInventory (10.01s)
niks3-go-unit-tests> === CONT  TestGCMetrics
niks3-go-unit-tests> 2026/10/08 12:59:31 WARN mTLS auth: subject not in bound subjects subject="CN=reader"
niks3-go-unit-tests> 2026/10/08 12:59:31 WARN mTLS auth: subject not in bound subjects subject="CN=reader"
niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (11.93s)
niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences
niks3-go-unit-tests> 2026/10/08 12:59:32 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:32 INFO Received complete push request method=POST path=/api/pushes/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:32 INFO Completed push id=1 objects=4 duration=88.511416ms
niks3-go-unit-tests> 2026/10/08 12:59:32 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete
niks3-go-unit-tests> 2026/10/08 12:59:32 INFO Completed upload id=2 objects=2 duration=1.10075ms
niks3-go-unit-tests> --- PASS: TestMetricsCommit (11.86s)
niks3-go-unit-tests> === CONT  TestGCAdvisoryLockBlocksConcurrentRun
niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug
niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /private/var/tb/8/scratch/f058402f878f26dd/build/TestNARDeduplicationMetadataUploadBug4288984991/001/store/y97m1d9q25cr3jbr2f8n4gqxpvyakjcg-file1.txt
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO lead: acquired remote=192.0.2.1:1234
niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest
niks3-go-unit-tests>     orphaned_objects_gc_test.go:422: Created 10 active closures, 5 to-delete closures, 20 orphaned chains
niks3-go-unit-tests>     orphaned_objects_gc_test.go:440: Marked 210 objects for deletion
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:33 WARN Failed to abort redundant multipart upload, keeping its row object_key=nar/gggggggggggggggggggggggggggggg0200000000000000000000.nar.zst upload_id=ZDUzYjQ1ZmUtMDk3Yy00YjM2LTliOGEtMWE3OTFkNzM1M2Q4LmU0YWM3ZjU4LTI1MjAtNDM5Zi1hNDVkLTRkNTg3NzVmMzc5MHgxNzkxNDY0MzcwNTI0NjQ0MDAw error="Get \"http://127.0.0.1:1/bucket20/?location=\": dial tcp 127.0.0.1:1: connect: connection refused"
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0200000000000000000000.nar.zst upload_id=ZDUzYjQ1ZmUtMDk3Yy00YjM2LTliOGEtMWE3OTFkNzM1M2Q4LjgxYzY5MGMwLWIzOWEtNGU5Yi1iMTAxLTEwMzExOWFmM2FhOHgxNzkxNDY0MzcwNDQ3MDI4MDAw parts=12
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Uploading y97m1d9q25cr3jbr2f8n4gqxpvyakjcg-file1.txt (160B)
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Received cleanup request method=DELETE path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Aborted multipart uploads count=1 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:33 WARN Failed to register uploaded object key=y97m1d9q25cr3jbr2f8n4gqxpvyakjcg.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:33 WARN Failed to register uploaded object key=nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Signed narinfos id=1 count=1
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Uploading 1 narinfos
niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (15.27s)
niks3-go-unit-tests> === CONT  TestClientMultipleUploads
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Received complete push request method=POST path=/api/pushes/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:33 WARN Failed to register uploaded object key=y97m1d9q25cr3jbr2f8n4gqxpvyakjcg.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Completed push id=1 objects=3 duration=25.887416ms
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Upload complete. (326ms)
niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug
niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:
niks3-go-unit-tests>         StorePath: /private/var/tb/8/scratch/f058402f878f26dd/build/TestNARDeduplicationMetadataUploadBug4288984991/001/store/y97m1d9q25cr3jbr2f8n4gqxpvyakjcg-file1.txt
niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst
niks3-go-unit-tests>         Compression: zstd
niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf
niks3-go-unit-tests>         NarSize: 160
niks3-go-unit-tests>         References: 
niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf
niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)
niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):
niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}
niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (10.60s)
niks3-go-unit-tests> === CONT  TestLeadIncumbentAnnouncesAtOnce
niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug
niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /private/var/tb/8/scratch/f058402f878f26dd/build/TestNARDeduplicationMetadataUploadBug4288984991/001/store/zg07vlb6yh2hf30rfhdr5h2f2srxr7h7-file2.txt
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign
niks3-go-unit-tests> 2026/10/08 12:59:33 WARN Failed to register uploaded object key=zg07vlb6yh2hf30rfhdr5h2f2srxr7h7.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Signed narinfos id=2 count=1
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Uploading 1 narinfos
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Received complete push request method=POST path=/api/pushes/2/complete
niks3-go-unit-tests> 2026/10/08 12:59:33 WARN Failed to register uploaded object key=zg07vlb6yh2hf30rfhdr5h2f2srxr7h7.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Completed push id=2 objects=3 duration=693.584µs
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Upload complete. (118ms)
niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:
niks3-go-unit-tests>         StorePath: /private/var/tb/8/scratch/f058402f878f26dd/build/TestNARDeduplicationMetadataUploadBug4288984991/001/store/zg07vlb6yh2hf30rfhdr5h2f2srxr7h7-file2.txt
niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst
niks3-go-unit-tests>         Compression: zstd
niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf
niks3-go-unit-tests>         NarSize: 160
niks3-go-unit-tests>         References: 
niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf
niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)
niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):
niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}
niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (12.05s)
niks3-go-unit-tests> === CONT  TestLeadElectsOneAndHandsOver
niks3-go-unit-tests> 2026/10/08 12:59:33 WARN readiness check failed error="closed pool"
niks3-go-unit-tests> --- PASS: TestService_readinessHandler (10.95s)
niks3-go-unit-tests> === CONT  TestConnectWaitsForAPeerMigration
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Aborted multipart uploads count=1 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Received cleanup request method=DELETE path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:33 INFO Aborted multipart uploads count=1 kept=0
niks3-go-unit-tests> --- PASS: TestPendingCleanupUsesOneCutoff (15.90s)
niks3-go-unit-tests> === CONT  TestResolveDBConnectionString
niks3-go-unit-tests> === RUN   TestResolveDBConnectionString/flag_wins
niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString/flag_wins
niks3-go-unit-tests> === RUN   TestResolveDBConnectionString/file_when_flag_empty
niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString/file_when_flag_empty
niks3-go-unit-tests> === RUN   TestResolveDBConnectionString/missing_file_is_an_error
niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString/missing_file_is_an_error
niks3-go-unit-tests> === RUN   TestResolveDBConnectionString/PGHOST_allows_empty
niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString/PGHOST_allows_empty
niks3-go-unit-tests> === RUN   TestResolveDBConnectionString/nothing_configured
niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString/nothing_configured
niks3-go-unit-tests> === CONT  TestConcurrentCommitsSharingObjectsDoNotDeadlock
niks3-go-unit-tests> 2026/10/08 12:59:34 INFO lead: released remote=192.0.2.1:1234
niks3-go-unit-tests> --- PASS: TestLeadEndsOnShutdown (11.08s)
niks3-go-unit-tests> === CONT  TestDeleteClosuresRacingPins
niks3-go-unit-tests> 2026/10/08 12:59:34 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:34 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:34 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:34 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:34 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:34 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:34 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:34 INFO Vacuumed table table=objects
niks3-go-unit-tests> 2026/10/08 12:59:34 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> --- PASS: TestCommitRacingPendingCleanupKeepsObjects (10.68s)
niks3-go-unit-tests> === CONT  TestClientFallsBackToClosures
niks3-go-unit-tests> 2026/10/08 12:59:35 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:35 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:35 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0
niks3-go-unit-tests> --- PASS: TestPushWaitsForSweepHoldingRow (9.82s)
niks3-go-unit-tests> === CONT  TestClientPushesUseOnePush
niks3-go-unit-tests> 2026/10/08 12:59:35 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:35 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:35 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:35 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:35 INFO Vacuumed table table=objects
niks3-go-unit-tests> --- PASS: TestSweepRechecksPendingAfterLocking (9.53s)
niks3-go-unit-tests> === CONT  TestPinProtectsFromGC
niks3-go-unit-tests> --- PASS: TestGetExistingObjectsLocksRows (9.46s)
niks3-go-unit-tests> === CONT  TestClientSharedPathCommittedMidPush
niks3-go-unit-tests> --- PASS: TestRegisterCompletedObjectNeedsPendingRow (9.35s)
niks3-go-unit-tests> === CONT  TestClientWithDependencies
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:36 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Vacuumed table table=objects
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:36 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=1 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Vacuumed table table=objects
niks3-go-unit-tests> --- PASS: TestSweepSkipsRowsLockedByPush (8.13s)
niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:36 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:36 ERROR failed to remove object object=nar/dddddddddddddddddddddddddddddddddddddddddddddddddddd.nar.zst error="Access Denied."
niks3-go-unit-tests> 2026/10/08 12:59:36 ERROR failed to remove object object=dddddddddddddddddddddddddddddddd.narinfo error="Access Denied."
niks3-go-unit-tests> --- PASS: TestFailedS3DeleteKeepsTombstone (7.67s)
niks3-go-unit-tests> === CONT  TestPush_SkippedKeySurvivesGCBeforeCommit
niks3-go-unit-tests> 2026/10/08 12:59:36 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> --- PASS: TestCreatePendingClosureVerifyS3FailureReleasesConnection (8.01s)
niks3-go-unit-tests> === CONT  TestPush_RootsAreLockedInByteOrder
niks3-go-unit-tests> {"timestamp":"2026-10-08T12:59:37.091853Z","level":"ERROR","message":"save_config_with_opts: err: Io(Custom { kind: TooManyOpenFiles, error: \"Too many open files (os error 24)\" }), file: buckets/bucket43/.bucket-incarnation","target":"rustfs_ecstore::config::com","filename":"crates/ecstore/src/config/com.rs","line_number":813,"threadName":"rustfs-worker","threadId":"ThreadId(2)"}
niks3-go-unit-tests> {"timestamp":"2026-10-08T12:59:37.09327Z","level":"ERROR","message":"Heal scheduler task failed","event":"heal_scheduler_state","component":"heal","subsystem":"manager","task_id":"4f74abdc-bccc-4cd2-adfe-cd35c73072df","heal_type":"object","set":"pool_0_set_0","state":"task_failed","error":"Heal task execution failed: Object not found: .rustfs.sys/buckets/bucket43/.bucket-incarnation","target":"rustfs::heal::manager","filename":"crates/heal/src/heal/manager/scheduler.rs","line_number":269,"threadName":"rustfs-worker","threadId":"ThreadId(6)"}
niks3-go-unit-tests> {"timestamp":"2026-10-08T12:59:37.135491Z","level":"ERROR","duration":"411.884416ms","resp":"Response { status: 500, version: HTTP/1.1, headers: {\"content-type\": \"application/xml\"}, body: Body { once: b\"<?xml version=\\\"1.0\\\" encoding=\\\"UTF-8\\\"?><Error><Code>InternalError</Code><Message>We encountered an internal error, please try again.</Message></Error>\", remaining_limit: None } }","target":"s3s::service","filename":"/nix/var/nix/builds/nix-28079-3532540677/rustfs-1.0.0-vendor/source-registry-0/s3s-0.16.0/src/service.rs","line_number":680,"threadName":"rustfs-worker","threadId":"ThreadId(8)"}
niks3-go-unit-tests> {"timestamp":"2026-10-08T12:59:37.135586Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"4c700d5c-4a84-42ef-88ce-e82483f7a9da","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"PUT","uri":"/bucket43/","status_code":500,"suppressed_errors":0,"duration_ms":412,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(8)"}
niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest
niks3-go-unit-tests>     orphaned_objects_gc_test.go:503: Stress test completed successfully:
niks3-go-unit-tests>     orphaned_objects_gc_test.go:504:   - Active objects preserved: 20
niks3-go-unit-tests>     orphaned_objects_gc_test.go:505:   - Objects deleted: 210
niks3-go-unit-tests>     orphaned_objects_gc_test.go:506:   - Total GC'd: 210
niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (19.13s)
niks3-go-unit-tests> === CONT  TestPush_CompleteWaitsForCleanupOfThePush
niks3-go-unit-tests> --- PASS: TestObjectStatsLargeCommit (19.15s)
niks3-go-unit-tests> === CONT  TestPush_RepeatedRootCommitsOnce
niks3-go-unit-tests> 2026/10/08 12:59:37 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:37 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:37 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=3 objects-deleted-after-grace-period=3 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:37 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:37 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:37 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:37 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:38 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:38 INFO Vacuumed table table=objects
niks3-go-unit-tests> --- PASS: TestGCSweepDeliversEachKeyOnce (8.21s)
niks3-go-unit-tests> === CONT  TestPush_CompleteCommitsEveryRoot
niks3-go-unit-tests> --- PASS: TestTombstonedObjectOfferedWithoutWaiting (8.10s)
niks3-go-unit-tests> === CONT  TestPush_OverlappingRootsStoreOneRowPerKey
niks3-go-unit-tests> 2026/10/08 12:59:38 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> --- PASS: TestDeduplicatedObjectsRecordedAsPending (7.77s)
niks3-go-unit-tests> === CONT  TestReadRedirectUsesPublicS3URL
niks3-go-unit-tests> 2026/10/08 12:59:38 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:38 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:39 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=objects
niks3-go-unit-tests> --- PASS: TestGCSweepSkipsPendingObjects (8.56s)
niks3-go-unit-tests> === CONT  TestPresentNotReportedWhileGCDeletesClosure/stale
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:39 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=objects
niks3-go-unit-tests> --- PASS: TestGCMetrics (8.31s)
niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=objects
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:39 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:39 INFO Vacuumed table table=objects
niks3-go-unit-tests> --- PASS: TestPushDedupSurvivesConcurrentGC (9.05s)
niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet
niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (8.54s)
niks3-go-unit-tests> === CONT  TestReadRedirectKeepsNarinfoProxied
niks3-go-unit-tests> 2026/10/08 12:59:40 ERROR failed to check GC advisory lock error="query pg_locks: failed to connect to `user=_tribuchetbld8 database=niks3`: /nonexistent/.s.PGSQL.5432 (/nonexistent): dial error: dial unix /nonexistent/.s.PGSQL.5432: connect: no such file or directory"
niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (7.94s)
niks3-go-unit-tests> === CONT  TestReadProxyOutlastsServerWriteTimeout
niks3-go-unit-tests> === NAME  TestClientMultipleUploads
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO lead: acquired remote=192.0.2.1:1234
niks3-go-unit-tests>     client_integration_test.go:358: Created store path 0: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientMultipleUploads2911288537/001/store/iz0230zgkcy9ynwk29bzl8srjdr31cn9-test-file-0.txt
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO lead: released remote=192.0.2.1:1234
niks3-go-unit-tests> --- PASS: TestLeadIncumbentAnnouncesAtOnce (7.80s)
niks3-go-unit-tests> === CONT  TestReadRedirectNar
niks3-go-unit-tests> === NAME  TestClientMultipleUploads
niks3-go-unit-tests>     client_integration_test.go:358: Created store path 1: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientMultipleUploads2911288537/001/store/qxh5gka9v1w8251plf37vvwjp1j8d2lw-test-file-1.txt
niks3-go-unit-tests>     client_integration_test.go:358: Created store path 2: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientMultipleUploads2911288537/001/store/31rm8glzv491369a1hjpw4jnzd0nj4ai-test-file-2.txt
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO lead: acquired remote=192.0.2.1:1234
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO Uploading qxh5gka9v1w8251plf37vvwjp1j8d2lw-test-file-1.txt (160B)
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO Uploading iz0230zgkcy9ynwk29bzl8srjdr31cn9-test-file-0.txt (160B)
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO Uploading 31rm8glzv491369a1hjpw4jnzd0nj4ai-test-file-2.txt (160B)
niks3-go-unit-tests> 2026/10/08 12:59:41 WARN Failed to register uploaded object key=nar/13p9pqi0ga6fx2apcwls9hjnv7nmf740ggpn5zvfzdsp5vz2bbg4.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:41 WARN Failed to register uploaded object key=nar/0ahq3izw3h0b0c08p3xdfclh758fwxnp85ccvflasb4vnfsv50ri.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:41 WARN Failed to register uploaded object key=iz0230zgkcy9ynwk29bzl8srjdr31cn9.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:41 WARN Failed to register uploaded object key=31rm8glzv491369a1hjpw4jnzd0nj4ai.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:41 WARN Failed to register uploaded object key=qxh5gka9v1w8251plf37vvwjp1j8d2lw.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:41 WARN Failed to register uploaded object key=nar/1823g6vx4q5zqvr73m7lkq1wpk40fin7bsc792di430yk7sg4lka.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO Signed narinfos id=1 count=3
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO Uploading 3 narinfos
niks3-go-unit-tests> 2026/10/08 12:59:41 WARN Failed to register uploaded object key=31rm8glzv491369a1hjpw4jnzd0nj4ai.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:41 WARN Failed to register uploaded object key=iz0230zgkcy9ynwk29bzl8srjdr31cn9.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO Received complete push request method=POST path=/api/pushes/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:41 WARN Failed to register uploaded object key=qxh5gka9v1w8251plf37vvwjp1j8d2lw.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO Completed push id=1 objects=9 duration=37.3635ms
niks3-go-unit-tests> 2026/10/08 12:59:41 INFO Upload complete. (231ms)
niks3-go-unit-tests>     client_integration_test.go:369: Uploaded 3 paths in 277.365333ms
niks3-go-unit-tests> --- PASS: TestConnectWaitsForAPeerMigration (8.12s)
niks3-go-unit-tests> === CONT  TestReadProxyHead
niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (8.77s)
niks3-go-unit-tests> === CONT  TestReadProxyDisabled
niks3-go-unit-tests> 2026/10/08 12:59:42 INFO lead: released remote=192.0.2.1:1234
niks3-go-unit-tests> 2026/10/08 12:59:42 INFO lead: acquired remote=192.0.2.1:1234
niks3-go-unit-tests> --- PASS: TestConcurrentCommitsSharingObjectsDoNotDeadlock (9.68s)
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Uploading 2 paths to 127.0.0.1 (1 already cached)
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Uploading vr16kjng3nigjlxg881yq8l62xdfacwa-b (256B)
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Uploading kazzg1yl067nmhmi8jd2b51x3qknx8kb-shared-dep (136B)
niks3-go-unit-tests> 2026/10/08 12:59:43 WARN Failed to register uploaded object key=nar/1n6cciywizmdvnghp14bkvasxdkl97i02jyr036w0g2k7yykjwhm.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:43 WARN Failed to register uploaded object key=kazzg1yl067nmhmi8jd2b51x3qknx8kb.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:43 WARN Failed to register uploaded object key=n749z5saprzg8r1rack0m52pf1i6mdx7.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:43 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:43 WARN Failed to register uploaded object key=vr16kjng3nigjlxg881yq8l62xdfacwa.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Signed narinfos id=1 count=2
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Signed narinfos id=2 count=2
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Uploading 4 narinfos
niks3-go-unit-tests> === NAME  TestPinProtectsFromGC
niks3-go-unit-tests>     client_integration_test.go:731: Pinned store path: /private/var/tb/8/scratch/f058402f878f26dd/build/TestPinProtectsFromGC4181760145/001/store/z5gn0hlkhmajx3l49k9ixm7p3bcbiz8a-pinned-file.txt
niks3-go-unit-tests>     client_integration_test.go:732: Unpinned store path: /private/var/tb/8/scratch/f058402f878f26dd/build/TestPinProtectsFromGC4181760145/001/store/cbli1i3j83mp7szgz7ziq3yhr2js0zwq-unpinned-file.txt
niks3-go-unit-tests> 2026/10/08 12:59:43 WARN Failed to register uploaded object key=kazzg1yl067nmhmi8jd2b51x3qknx8kb.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:43 WARN Failed to register uploaded object key=vr16kjng3nigjlxg881yq8l62xdfacwa.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:43 WARN Failed to register uploaded object key=n749z5saprzg8r1rack0m52pf1i6mdx7.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:43 WARN Failed to register uploaded object key=kazzg1yl067nmhmi8jd2b51x3qknx8kb.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Completed upload id=1 objects=6 duration=36.536667ms
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO lead: released remote=192.0.2.1:1234
niks3-go-unit-tests> --- PASS: TestLeadElectsOneAndHandsOver (10.18s)
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete
niks3-go-unit-tests> === CONT  TestReadProxy404
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Completed upload id=2 objects=6 duration=572.375µs
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Upload complete. (336ms)
niks3-go-unit-tests> === NAME  TestClientFallsBackToClosures
niks3-go-unit-tests>     client_pushes_test.go:112: Retrieved narinfo from S3:
niks3-go-unit-tests>         StorePath: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientFallsBackToClosures2927289381/001/store/kazzg1yl067nmhmi8jd2b51x3qknx8kb-shared-dep
niks3-go-unit-tests>         URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst
niks3-go-unit-tests>         Compression: zstd
niks3-go-unit-tests>         NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82
niks3-go-unit-tests>         NarSize: 136
niks3-go-unit-tests>         References: 
niks3-go-unit-tests>         CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n
niks3-go-unit-tests>     client_pushes_test.go:112: Retrieved narinfo from S3:
niks3-go-unit-tests>         StorePath: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientFallsBackToClosures2927289381/001/store/n749z5saprzg8r1rack0m52pf1i6mdx7-a
niks3-go-unit-tests>         URL: nar/1n6cciywizmdvnghp14bkvasxdkl97i02jyr036w0g2k7yykjwhm.nar.zst
niks3-go-unit-tests>         Compression: zstd
niks3-go-unit-tests>         NarHash: sha256:1n6cciywizmdvnghp14bkvasxdkl97i02jyr036w0g2k7yykjwhm
niks3-go-unit-tests>         NarSize: 256
niks3-go-unit-tests>         References: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientFallsBackToClosures2927289381/001/store/kazzg1yl067nmhmi8jd2b51x3qknx8kb-shared-dep
niks3-go-unit-tests>         CA: text:sha256:0zhqny3phmlkkvrlgmdw116l2zznkl3sb0m94lx4fi2brqfn6lg9
niks3-go-unit-tests>     client_pushes_test.go:112: Retrieved narinfo from S3:
niks3-go-unit-tests>         StorePath: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientFallsBackToClosures2927289381/001/store/vr16kjng3nigjlxg881yq8l62xdfacwa-b
niks3-go-unit-tests>         URL: nar/1n6cciywizmdvnghp14bkvasxdkl97i02jyr036w0g2k7yykjwhm.nar.zst
niks3-go-unit-tests>         Compression: zstd
niks3-go-unit-tests>         NarHash: sha256:1n6cciywizmdvnghp14bkvasxdkl97i02jyr036w0g2k7yykjwhm
niks3-go-unit-tests>         NarSize: 256
niks3-go-unit-tests>         References: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientFallsBackToClosures2927289381/001/store/kazzg1yl067nmhmi8jd2b51x3qknx8kb-shared-dep
niks3-go-unit-tests>         CA: text:sha256:0zhqny3phmlkkvrlgmdw116l2zznkl3sb0m94lx4fi2brqfn6lg9
niks3-go-unit-tests> 2026/10/08 12:59:43 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading z5gn0hlkhmajx3l49k9ixm7p3bcbiz8a-pinned-file.txt (128B)
niks3-go-unit-tests> --- PASS: TestClientFallsBackToClosures (9.38s)
niks3-go-unit-tests> === CONT  TestIsValidCachePath
niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo
niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars
niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst
niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz
niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2
niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed
niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls
niks3-go-unit-tests> === RUN   TestIsValidCachePath/log
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log
niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation
niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info
niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html
niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent
niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle
niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e
niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u
niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path
niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty
niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash
niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension
niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash
niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash
niks3-go-unit-tests> === CONT  TestParseSingleRange
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=z5gn0hlkhmajx3l49k9ixm7p3bcbiz8a.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> === RUN   TestParseSingleRange/none
niks3-go-unit-tests> === PAUSE TestParseSingleRange/none
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=nar/0qmhsg42pqsz7x6hh02j8gznnac3yi3qpjhrqbwvz88i194jbb5s.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit
niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit
niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored
niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored
niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash
niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash
niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty
niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty
niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start
niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start
niks3-go-unit-tests> === RUN   TestParseSingleRange/closed
niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed
niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended
niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended
niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size
niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size
niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix
niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix
niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size
niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size
niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte
niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte
niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF
niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF
niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF
niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF
niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Signed narinfos id=1 count=1
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading 1 narinfos
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Received complete push request method=POST path=/api/pushes/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=z5gn0hlkhmajx3l49k9ixm7p3bcbiz8a.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Completed push id=1 objects=3 duration=26.689625ms
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Upload complete. (266ms)
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading cbli1i3j83mp7szgz7ziq3yhr2js0zwq-unpinned-file.txt (128B)
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=cbli1i3j83mp7szgz7ziq3yhr2js0zwq.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=nar/0gh0wdnr6gmk4r5sfzzh12isnl14rgmkrwy1ws41hpsrlndclg5h.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Signed narinfos id=2 count=1
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading 1 narinfos
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Received complete push request method=POST path=/api/pushes/2/complete
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=cbli1i3j83mp7szgz7ziq3yhr2js0zwq.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Completed push id=2 objects=3 duration=791.666µs
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Upload complete. (117ms)
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Starting cleanup of old closures method=DELETE path=/api/closures
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Garbage collection started
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading 2 paths to 127.0.0.1 (1 already cached)
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading b86nan5phv27mhkb0a8zd6adh3viv3is-a (256B)
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading anawnw1z1f8dxwrgcx19n8f9drrcij8h-shared-dep (136B)
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=4fbdngavabnvw8p104l0gxsgwq6yz7rh.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=b86nan5phv27mhkb0a8zd6adh3viv3is.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=nar/1d8sb1w981kmwyygddhyfyj15azaj17bl9bb5pix40g6hb8qgifh.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=anawnw1z1f8dxwrgcx19n8f9drrcij8h.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Signed narinfos id=1 count=3
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading 3 narinfos
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=4fbdngavabnvw8p104l0gxsgwq6yz7rh.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=b86nan5phv27mhkb0a8zd6adh3viv3is.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Received complete push request method=POST path=/api/pushes/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=anawnw1z1f8dxwrgcx19n8f9drrcij8h.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Completed push id=1 objects=8 duration=26.339334ms
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Upload complete. (260ms)
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=3 objects-failed-to-delete=0
niks3-go-unit-tests> === NAME  TestClientPushesUseOnePush
niks3-go-unit-tests>     client_pushes_test.go:97: Retrieved narinfo from S3:
niks3-go-unit-tests>         StorePath: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientPushesUseOnePush2357509665/001/store/anawnw1z1f8dxwrgcx19n8f9drrcij8h-shared-dep
niks3-go-unit-tests>         URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst
niks3-go-unit-tests>         Compression: zstd
niks3-go-unit-tests>         NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82
niks3-go-unit-tests>         NarSize: 136
niks3-go-unit-tests>         References: 
niks3-go-unit-tests>         CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n
niks3-go-unit-tests>     client_pushes_test.go:97: Retrieved narinfo from S3:
niks3-go-unit-tests>         StorePath: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientPushesUseOnePush2357509665/001/store/b86nan5phv27mhkb0a8zd6adh3viv3is-a
niks3-go-unit-tests>         URL: nar/1d8sb1w981kmwyygddhyfyj15azaj17bl9bb5pix40g6hb8qgifh.nar.zst
niks3-go-unit-tests>         Compression: zstd
niks3-go-unit-tests>         NarHash: sha256:1d8sb1w981kmwyygddhyfyj15azaj17bl9bb5pix40g6hb8qgifh
niks3-go-unit-tests>         NarSize: 256
niks3-go-unit-tests>         References: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientPushesUseOnePush2357509665/001/store/anawnw1z1f8dxwrgcx19n8f9drrcij8h-shared-dep
niks3-go-unit-tests>         CA: text:sha256:0fimc055asv0wbjy3fiahcvbscc90cl0bpnsznz21ihlbffzvjyb
niks3-go-unit-tests>     client_pushes_test.go:97: Retrieved narinfo from S3:
niks3-go-unit-tests>         StorePath: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientPushesUseOnePush2357509665/001/store/4fbdngavabnvw8p104l0gxsgwq6yz7rh-b
niks3-go-unit-tests>         URL: nar/1d8sb1w981kmwyygddhyfyj15azaj17bl9bb5pix40g6hb8qgifh.nar.zst
niks3-go-unit-tests>         Compression: zstd
niks3-go-unit-tests>         NarHash: sha256:1d8sb1w981kmwyygddhyfyj15azaj17bl9bb5pix40g6hb8qgifh
niks3-go-unit-tests>         NarSize: 256
niks3-go-unit-tests>         References: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientPushesUseOnePush2357509665/001/store/anawnw1z1f8dxwrgcx19n8f9drrcij8h-shared-dep
niks3-go-unit-tests>         CA: text:sha256:0fimc055asv0wbjy3fiahcvbscc90cl0bpnsznz21ihlbffzvjyb
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading 2 paths to 127.0.0.1 (0 already cached)
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading 34q9rqqq2yd2l2zbpiw2ym0928d56hmk-shared-dep (136B)
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading 0zq5dyfq4x4bd186c5wnckr7inh961l2-top (264B)
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> --- PASS: TestClientPushesUseOnePush (9.51s)
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> === CONT  TestReadProxyContentNegotiation
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=34q9rqqq2yd2l2zbpiw2ym0928d56hmk.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=0zq5dyfq4x4bd186c5wnckr7inh961l2.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=nar/0yab15yrpgz9scwz0xdna9qgm2wsmdx50g2v5ji98scng90j6ph9.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Signed narinfos id=1 count=2
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Uploading 2 narinfos
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Vacuumed table table=objects
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=0zq5dyfq4x4bd186c5wnckr7inh961l2.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Received complete push request method=POST path=/api/pushes/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:44 WARN Failed to register uploaded object key=34q9rqqq2yd2l2zbpiw2ym0928d56hmk.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Completed push id=1 objects=6 duration=27.563959ms
niks3-go-unit-tests> 2026/10/08 12:59:44 INFO Upload complete. (276ms)
niks3-go-unit-tests> === NAME  TestClientSharedPathCommittedMidPush
niks3-go-unit-tests>     client_integration_test.go:680: Retrieved narinfo from S3:
niks3-go-unit-tests>         StorePath: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientSharedPathCommittedMidPush2431481064/001/store/34q9rqqq2yd2l2zbpiw2ym0928d56hmk-shared-dep
niks3-go-unit-tests>         URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst
niks3-go-unit-tests>         Compression: zstd
niks3-go-unit-tests>         NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82
niks3-go-unit-tests>         NarSize: 136
niks3-go-unit-tests>         References: 
niks3-go-unit-tests>         CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n
niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (8.43s)
niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed
niks3-go-unit-tests> === NAME  TestClientSharedPathCommittedMidPush
niks3-go-unit-tests>     client_integration_test.go:680: Retrieved narinfo from S3:
niks3-go-unit-tests>         StorePath: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientSharedPathCommittedMidPush2431481064/001/store/0zq5dyfq4x4bd186c5wnckr7inh961l2-top
niks3-go-unit-tests>         URL: nar/0yab15yrpgz9scwz0xdna9qgm2wsmdx50g2v5ji98scng90j6ph9.nar.zst
niks3-go-unit-tests>         Compression: zstd
niks3-go-unit-tests>         NarHash: sha256:0yab15yrpgz9scwz0xdna9qgm2wsmdx50g2v5ji98scng90j6ph9
niks3-go-unit-tests>         NarSize: 264
niks3-go-unit-tests>         References: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientSharedPathCommittedMidPush2431481064/001/store/34q9rqqq2yd2l2zbpiw2ym0928d56hmk-shared-dep
niks3-go-unit-tests>         CA: text:sha256:0hdgd6wqgi2m94nn5kh46h8ianjlps9qprx8k0mkswa7g4zqdal7
niks3-go-unit-tests> --- PASS: TestClientSharedPathCommittedMidPush (9.51s)
niks3-go-unit-tests> === CONT  TestReadProxyNarinfo
niks3-go-unit-tests> === NAME  TestClientWithDependencies
niks3-go-unit-tests>     client_integration_test.go:613: Built derivation: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientWithDependencies2886328443/001/store/w7nm6866dyyskxsn6xsza9msi4gmbq20-test-script
niks3-go-unit-tests>     client_integration_test.go:615: Found 1 dependencies (including self)
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Uploading w7nm6866dyyskxsn6xsza9msi4gmbq20-test-script (136B)
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Received complete push request method=POST path=/api/pushes/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:45 WARN Failed to register uploaded object key=nar/1s7yghixzpinyqm7g59kyn3sav81pmnsdi5v63m6x4r2vacy907j.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:45 WARN Failed to register uploaded object key=w7nm6866dyyskxsn6xsza9msi4gmbq20.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:45 WARN Failed to register uploaded object key=log/1ipsidf0a9jwxmjj5p9b1g9q86j7kq96-test-script.drv error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Signed narinfos id=1 count=1
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Uploading 1 narinfos
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Completed push id=1 objects=4 duration=43.57625ms
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Received complete push request method=POST path=/api/pushes/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:45 WARN Failed to register uploaded object key=w7nm6866dyyskxsn6xsza9msi4gmbq20.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Completed push id=1 objects=4 duration=27.699167ms
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Upload complete. (232ms)
niks3-go-unit-tests>     client_integration_test.go:617: Skipping nix copy test - isolated store (/private/var/tb/8/scratch/f058402f878f26dd/build/TestClientWithDependencies2886328443/001/store) requires matching store prefix
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:45 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=2 objects-deleted-after-grace-period=2 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> --- PASS: TestClientWithDependencies (9.79s)
niks3-go-unit-tests> === CONT  TestService_ReadScope_PublicByDefault
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Vacuumed table table=objects
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Received complete push request method=POST path=/api/pushes/2/complete
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Completed push id=2 objects=4 duration=7.351875ms
niks3-go-unit-tests> --- PASS: TestPush_SkippedKeySurvivesGCBeforeCommit (9.00s)
niks3-go-unit-tests> === CONT  TestClientIntegration
niks3-go-unit-tests> 2026-10-08 12:59:45.632 UTC [45583] ERROR:  could not obtain lock on row in relation "closures"
niks3-go-unit-tests> 2026-10-08 12:59:45.632 UTC [45583] STATEMENT:  SELECT 1 FROM closures WHERE key = $1 FOR UPDATE NOWAIT
niks3-go-unit-tests> 2026-10-08 12:59:45.636 UTC [45583] ERROR:  could not obtain lock on row in relation "closures"
niks3-go-unit-tests> 2026-10-08 12:59:45.636 UTC [45583] STATEMENT:  SELECT 1 FROM closures WHERE key = $1 FOR UPDATE NOWAIT
niks3-go-unit-tests> 2026-10-08 12:59:45.637 UTC [45583] ERROR:  could not obtain lock on row in relation "closures"
niks3-go-unit-tests> 2026-10-08 12:59:45.637 UTC [45583] STATEMENT:  SELECT 1 FROM closures WHERE key = $1 FOR UPDATE NOWAIT
niks3-go-unit-tests> 2026-10-08 12:59:45.638 UTC [45583] ERROR:  could not obtain lock on row in relation "closures"
niks3-go-unit-tests> 2026-10-08 12:59:45.638 UTC [45583] STATEMENT:  SELECT 1 FROM closures WHERE key = $1 FOR UPDATE NOWAIT
niks3-go-unit-tests> 2026-10-08 12:59:45.642 UTC [45583] ERROR:  could not obtain lock on row in relation "closures"
niks3-go-unit-tests> 2026-10-08 12:59:45.642 UTC [45583] STATEMENT:  SELECT 1 FROM closures WHERE key = $1 FOR UPDATE NOWAIT
niks3-go-unit-tests> --- PASS: TestPush_RootsAreLockedInByteOrder (8.63s)
niks3-go-unit-tests> === CONT  TestClientErrorHandling
niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath
niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath
niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken
niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken
niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable
niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable
niks3-go-unit-tests> === CONT  TestClientCADerivations
niks3-go-unit-tests> 2026/10/08 12:59:45 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026-10-08 12:59:46.166 UTC [45591] ERROR:  Push does not exist: id=1
niks3-go-unit-tests> 2026-10-08 12:59:46.166 UTC [45591] CONTEXT:  PL/pgSQL function commit_push(bigint) line 11 at RAISE
niks3-go-unit-tests> 2026-10-08 12:59:46.166 UTC [45591] STATEMENT:  SELECT commit_push($1)
niks3-go-unit-tests> --- PASS: TestPush_CompleteWaitsForCleanupOfThePush (9.01s)
niks3-go-unit-tests> === CONT  TestCacheStatsHandler
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Received complete push request method=POST path=/api/pushes/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Completed push id=1 objects=4 duration=32.624208ms
niks3-go-unit-tests> --- PASS: TestPush_RepeatedRootCommitsOnce (9.06s)
niks3-go-unit-tests> === CONT  TestCacheConfigHandler
niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer
niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer
niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured
niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured
niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys
niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys
niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator
niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator
niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=3 objects_failed=0
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Received create pin request method=POST path=/api/pins/myapp
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Created/updated pin name=myapp store_path=/private/var/tb/8/scratch/f058402f878f26dd/build/TestPinProtectsFromGC4181760145/001/store/z5gn0hlkhmajx3l49k9ixm7p3bcbiz8a-pinned-file.txt narinfo_key=z5gn0hlkhmajx3l49k9ixm7p3bcbiz8a.narinfo
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Starting cleanup of old closures method=DELETE path=/api/closures
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Garbage collection started
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:46 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Vacuumed table table=objects
niks3-go-unit-tests> --- PASS: TestPush_OverlappingRootsStoreOneRowPerKey (8.54s)
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53590/oidc
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Received complete push request method=POST path=/api/pushes/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:46 INFO Completed push id=1 objects=6 duration=40.016375ms
niks3-go-unit-tests> --- PASS: TestPush_CompleteCommitsEveryRoot (8.96s)
niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC
niks3-go-unit-tests> 2026/10/08 12:59:47 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53598/oidc
niks3-go-unit-tests> --- PASS: TestReadRedirectUsesPublicS3URL (8.70s)
niks3-go-unit-tests> === CONT  TestPresentNotReportedWhileGCDeletesClosure/fresh
niks3-go-unit-tests> --- PASS: TestDeleteClosuresRacingPins (13.21s)
niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader
niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (7.98s)
niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects
niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info
niks3-go-unit-tests> 2026/10/08 12:59:47 INFO Received uploads request method=POST path=/
niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal
niks3-go-unit-tests> 2026/10/08 12:59:47 INFO Received uploads request method=POST path=/
niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key
niks3-go-unit-tests> 2026/10/08 12:59:47 INFO Received complete multipart upload request method=POST path=/
niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key
niks3-go-unit-tests> 2026/10/08 12:59:48 INFO Received request for more parts method=POST path=/
niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)
niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)
niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.01s)
niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)
niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)
niks3-go-unit-tests> === CONT  TestPendingClosureWriteTimeout/empty
niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo
niks3-go-unit-tests> === CONT  TestPendingClosureWriteTimeout/670k_objects
niks3-go-unit-tests> === CONT  TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline
niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (8.17s)
niks3-go-unit-tests> === CONT  TestPendingClosureWriteTimeout/400_objects
niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size
niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar
niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar
niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)
niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)
niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)
niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)
niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type
niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html
niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.03s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)
niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure
niks3-go-unit-tests> 2026/10/08 12:59:48 INFO Received uploads request method=POST path=/
niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts
niks3-go-unit-tests> 2026/10/08 12:59:48 INFO Received request for more parts method=POST path=/
niks3-go-unit-tests> === NAME  TestPinProtectsFromGC
niks3-go-unit-tests>     client_integration_test.go:840: Pin successfully protected closure from garbage collection
niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart
niks3-go-unit-tests> 2026/10/08 12:59:48 INFO Received complete multipart upload request method=POST path=/
niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.08s)
niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.30s)
niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.02s)
niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.02s)
niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA
niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file
niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file
niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.01s)
niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)
niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)
niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.01s)
niks3-go-unit-tests> === CONT  TestPush_RejectsBadRequests/no_roots
niks3-go-unit-tests> 2026/10/08 12:59:48 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> === CONT  TestPush_RejectsBadRequests/bad_root
niks3-go-unit-tests> 2026/10/08 12:59:48 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> === CONT  TestPush_RejectsBadRequests/root_not_in_objects
niks3-go-unit-tests> 2026/10/08 12:59:48 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> === CONT  TestPush_RejectsBadRequests/no_objects
niks3-go-unit-tests> 2026/10/08 12:59:48 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> === CONT  TestGCEndsOnShutdown/before_the_run
niks3-go-unit-tests> --- PASS: TestPush_RejectsBadRequests (2.64s)
niks3-go-unit-tests>     --- PASS: TestPush_RejectsBadRequests/no_roots (0.00s)
niks3-go-unit-tests>     --- PASS: TestPush_RejectsBadRequests/bad_root (0.00s)
niks3-go-unit-tests>     --- PASS: TestPush_RejectsBadRequests/root_not_in_objects (0.00s)
niks3-go-unit-tests>     --- PASS: TestPush_RejectsBadRequests/no_objects (0.00s)
niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (13.33s)
niks3-go-unit-tests> === CONT  TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete
niks3-go-unit-tests> --- PASS: TestReadRedirectKeepsNarinfoProxied (8.27s)
niks3-go-unit-tests> === CONT  TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark
niks3-go-unit-tests> --- PASS: TestReadRedirectNar (7.63s)
niks3-go-unit-tests> === CONT  TestForceGCDuringPushOffersSweptObject/before_pending_rows
niks3-go-unit-tests> --- PASS: TestReadProxyHead (7.37s)
niks3-go-unit-tests> === CONT  TestForceGCDuringPushOffersSweptObject/after_presence_check
niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (7.53s)
niks3-go-unit-tests> === CONT  TestResolveDBConnectionString/flag_wins
niks3-go-unit-tests> === CONT  TestResolveDBConnectionString/PGHOST_allows_empty
niks3-go-unit-tests> === CONT  TestResolveDBConnectionString/nothing_configured
niks3-go-unit-tests> === CONT  TestResolveDBConnectionString/missing_file_is_an_error
niks3-go-unit-tests> === CONT  TestResolveDBConnectionString/file_when_flag_empty
niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo
niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html
niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash
niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension
niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash
niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty
niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle
niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e
niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent
niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path
niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed
niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u
niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info
niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls
niks3-go-unit-tests> === CONT  TestIsValidCachePath/log
niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation
niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz
niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst
niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz2
niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars
niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)
niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)
niks3-go-unit-tests> === CONT  TestParseSingleRange/none
niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended
niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF
niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF
niks3-go-unit-tests> --- PASS: TestResolveDBConnectionString (0.04s)
niks3-go-unit-tests>     --- PASS: TestResolveDBConnectionString/flag_wins (0.00s)
niks3-go-unit-tests>     --- PASS: TestResolveDBConnectionString/PGHOST_allows_empty (0.00s)
niks3-go-unit-tests>     --- PASS: TestResolveDBConnectionString/nothing_configured (0.00s)
niks3-go-unit-tests>     --- PASS: TestResolveDBConnectionString/missing_file_is_an_error (0.00s)
niks3-go-unit-tests>     --- PASS: TestResolveDBConnectionString/file_when_flag_empty (0.00s)
niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte
niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size
niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix
niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size
niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty
niks3-go-unit-tests> === CONT  TestParseSingleRange/closed
niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start
niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored
niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash
niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit
niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.01s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)
niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath
niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (6.25s)
niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable
niks3-go-unit-tests> 2026/10/08 12:59:50 WARN Request failed, retrying attempt=1 backoff=100ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present
niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (6.21s)
niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken
niks3-go-unit-tests> 2026/10/08 12:59:50 WARN Request failed, retrying attempt=2 backoff=211.345215ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present
niks3-go-unit-tests> 2026/10/08 12:59:50 WARN Request failed, retrying attempt=3 backoff=371.386209ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present
niks3-go-unit-tests> --- PASS: TestReadProxy404 (6.64s)
niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer
niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys
niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator
niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured
niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.01s)
niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)
niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)
niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)
niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)
niks3-go-unit-tests> 2026-10-08 12:59:50.878 UTC [45017] LOG:  checkpoint starting: wal
niks3-go-unit-tests> 2026/10/08 12:59:50 WARN Request failed, retrying attempt=4 backoff=797.799142ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present
niks3-go-unit-tests> --- PASS: TestReadProxyContentNegotiation (6.32s)
niks3-go-unit-tests> 2026/10/08 12:59:51 ERROR Failed to decode narinfo key=3hcdxyjf9yiq7qf3i4548drb6sjmwa1v.narinfo limit=16777216 error=<nil>
niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (6.50s)
niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (6.83s)
niks3-go-unit-tests> 2026/10/08 12:59:51 WARN Request failed, retrying attempt=5 backoff=1.754302468s error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present
niks3-go-unit-tests> --- PASS: TestService_ReadScope_PublicByDefault (6.45s)
niks3-go-unit-tests> === NAME  TestClientIntegration
niks3-go-unit-tests>     client_integration_test.go:286: Created store path: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientIntegration1525750824/002/store/p8v5k9dyk541akdpffqqbph6mabdwsya-test-file.txt
niks3-go-unit-tests> === NAME  TestClientCADerivations
niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientCADerivations2658373148/001/store/k89ayknhp4ynxk3nxrc0q21vnxggkf7a-ca-test
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (6.35s)
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Uploading p8v5k9dyk541akdpffqqbph6mabdwsya-test-file.txt (152B)
niks3-go-unit-tests> === NAME  TestClientCADerivations
niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:52 WARN Failed to register uploaded object key=p8v5k9dyk541akdpffqqbph6mabdwsya.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:52 WARN Failed to register uploaded object key=nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Signed narinfos id=1 count=1
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Uploading 1 narinfos
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Received complete push request method=POST path=/api/pushes/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:52 WARN Failed to register uploaded object key=p8v5k9dyk541akdpffqqbph6mabdwsya.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Completed push id=1 objects=3 duration=5.319959ms
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Upload complete. (98ms)
niks3-go-unit-tests> --- PASS: TestReadProxyOutlastsServerWriteTimeout (12.10s)
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO All 1 paths already cached
niks3-go-unit-tests> === NAME  TestClientIntegration
niks3-go-unit-tests>     client_integration_test.go:312: Retrieved narinfo from S3:
niks3-go-unit-tests>         StorePath: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientIntegration1525750824/002/store/p8v5k9dyk541akdpffqqbph6mabdwsya-test-file.txt
niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst
niks3-go-unit-tests>         Compression: zstd
niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1
niks3-go-unit-tests>         NarSize: 152
niks3-go-unit-tests>         References: 
niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1
niks3-go-unit-tests>     client_integration_test.go:313: Retrieved .ls file from S3 (compressed size: 77 bytes)
niks3-go-unit-tests>     client_integration_test.go:313: Decompressed .ls content (64 bytes):
niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}
niks3-go-unit-tests>     client_integration_test.go:316: Testing garbage collection...
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Starting cleanup of old closures method=DELETE path=/api/closures
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Garbage collection started
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Received push request method=POST path=/api/pushes
niks3-go-unit-tests> 2026/10/08 12:59:52 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Uploading k89ayknhp4ynxk3nxrc0q21vnxggkf7a-ca-test (144B)
niks3-go-unit-tests> 2026/10/08 12:59:52 WARN Failed to register uploaded object key=nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:52 WARN Failed to register uploaded object key=k89ayknhp4ynxk3nxrc0q21vnxggkf7a.ls error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:52 WARN Failed to register uploaded object key=log/kkffagnzqplgrm5pq049l4nzk6gyzfhs-ca-test.drv error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Signed narinfos id=1 count=1
niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (6.40s)
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Uploading 1 narinfos
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=3 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Received complete push request method=POST path=/api/pushes/1/complete
niks3-go-unit-tests> 2026/10/08 12:59:52 WARN Failed to register uploaded object key=k89ayknhp4ynxk3nxrc0q21vnxggkf7a.narinfo error="server returned 404: 404 page not found\n"
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Completed push id=1 objects=4 duration=15.601ms
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Upload complete. (160ms)
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> === NAME  TestClientCADerivations
niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientCADerivations2658373148/001/store/k89ayknhp4ynxk3nxrc0q21vnxggkf7a-ca-test
niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst
niks3-go-unit-tests>         Compression: zstd
niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n
niks3-go-unit-tests>         NarSize: 144
niks3-go-unit-tests>         References: 
niks3-go-unit-tests>         Deriver: /private/var/tb/8/scratch/f058402f878f26dd/build/TestClientCADerivations2658373148/001/store/kkffagnzqplgrm5pq049l4nzk6gyzfhs-ca-test.drv
niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n
niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...
niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations
niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:52 INFO Vacuumed table table=objects
niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: error: binary cache 's3://bucket84?endpoint=http://localhost:53104&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/private/var/tb/8/scratch/f058402f878f26dd/build/TestClientCADerivations2658373148/001/store'
niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 1
niks3-go-unit-tests> --- PASS: TestClientCADerivations (7.14s)
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/builder_may_write
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/builder_may_write
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/builder_may_not_admin
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/builder_may_not_admin
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/ops_may_admin
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/ops_may_admin
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/ops_may_not_write
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/ops_may_not_write
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/reader_may_not_write
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/reader_may_not_write
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/static_token_may_admin
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/static_token_may_admin
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/static_token_may_write
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/static_token_may_write
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/reader_may_read
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/reader_may_read
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/writer_implies_read
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/writer_implies_read
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/anonymous_may_not_read
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/anonymous_may_not_read
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/reader_may_read_with_netrc
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/reader_may_read_with_netrc
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/static_token_may_admin_with_netrc
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/static_token_may_admin_with_netrc
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read
niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC/empty_netrc_password_may_not_read
niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC/empty_netrc_password_may_not_read
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/builder_may_write
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/reader_may_read
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/reader_may_not_write
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/static_token_may_write
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/builder_may_not_admin
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/static_token_may_admin
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/static_token_may_admin_with_netrc
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/empty_netrc_password_may_not_read
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read
niks3-go-unit-tests> 2026/10/08 12:59:52 WARN Authentication failed token_preview=wrong token_length=5 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/anonymous_may_not_read
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/reader_may_read_with_netrc
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/ops_may_not_write
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/ops_may_admin
niks3-go-unit-tests> === CONT  TestService_RequireScope_OIDC/writer_implies_read
niks3-go-unit-tests> --- PASS: TestService_RequireScope_OIDC (6.31s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/builder_may_write (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/reader_may_read (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/reader_may_not_write (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/static_token_may_write (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/static_token_may_admin (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/static_token_may_admin_with_netrc (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/builder_may_not_admin (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/empty_netrc_password_may_not_read (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read (0.01s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/anonymous_may_not_read (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/reader_may_read_with_netrc (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/ops_may_not_write (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/ops_may_admin (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_RequireScope_OIDC/writer_implies_read (0.00s)
niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token
niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token
niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected
niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected
niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected
niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected
niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured
niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured
niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token
niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected
niks3-go-unit-tests> 2026/10/08 12:59:53 WARN Authentication failed token_preview=not-a-valid-jwt token_length=15 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]
niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured
niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected
niks3-go-unit-tests> 2026/10/08 12:59:53 WARN Authentication failed token_preview=eyJhbGciOi...SdOv41WhzA token_length=701 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]
niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (6.07s)
niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)
niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.01s)
niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.01s)
niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (6.13s)
niks3-go-unit-tests> 2026/10/08 12:59:53 WARN Request failed, retrying attempt=1 backoff=100ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config
niks3-go-unit-tests> 2026/10/08 12:59:53 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"
niks3-go-unit-tests> 2026/10/08 12:59:53 WARN mTLS auth: bound subjects configured but subject DN unavailable
niks3-go-unit-tests> 2026/10/08 12:59:53 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"
niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (5.89s)
niks3-go-unit-tests> 2026/10/08 12:59:53 WARN Request failed, retrying attempt=2 backoff=197.172727ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config
niks3-go-unit-tests> --- PASS: TestPresentNotReportedWhileGCDeletesClosure (0.00s)
niks3-go-unit-tests>     --- PASS: TestPresentNotReportedWhileGCDeletesClosure/stale (8.75s)
niks3-go-unit-tests>     --- PASS: TestPresentNotReportedWhileGCDeletesClosure/fresh (6.57s)
niks3-go-unit-tests> 2026/10/08 12:59:53 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:53 WARN Request failed, retrying attempt=3 backoff=374.314167ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config
niks3-go-unit-tests> 2026/10/08 12:59:54 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:54 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026-10-08 12:59:54.173 UTC [45685] ERROR:  canceling statement due to user request
niks3-go-unit-tests> 2026-10-08 12:59:54.173 UTC [45685] STATEMENT:  -- name: MarkStaleObjects :execrows
niks3-go-unit-tests> 	WITH RECURSIVE ct AS (
niks3-go-unit-tests> 	    SELECT timezone('UTC', now()) AS now
niks3-go-unit-tests> 	),
niks3-go-unit-tests> 	closure_reach AS (
niks3-go-unit-tests> 	    -- Start with all closure keys
niks3-go-unit-tests> 	    SELECT o.key, o.refs
niks3-go-unit-tests> 	    FROM objects o
niks3-go-unit-tests> 	    INNER JOIN closures c ON o.key = c.key
niks3-go-unit-tests> 	    UNION
niks3-go-unit-tests> 	    -- Recursively add all referenced objects
niks3-go-unit-tests> 	    SELECT o.key, o.refs
niks3-go-unit-tests> 	    FROM objects o
niks3-go-unit-tests> 	    INNER JOIN closure_reach cr ON o.key = ANY(cr.refs)
niks3-go-unit-tests> 	),
niks3-go-unit-tests> 	reachable_objects AS (
niks3-go-unit-tests> 	    SELECT DISTINCT key FROM closure_reach
niks3-go-unit-tests> 	),
niks3-go-unit-tests> 	stale_objects AS (
niks3-go-unit-tests> 	    SELECT o.key
niks3-go-unit-tests> 	    FROM objects AS o, ct
niks3-go-unit-tests> 	    WHERE
niks3-go-unit-tests> 	        NOT EXISTS (
niks3-go-unit-tests> 	            SELECT 1
niks3-go-unit-tests> 	            FROM reachable_objects ro
niks3-go-unit-tests> 	            WHERE ro.key = o.key
niks3-go-unit-tests> 	        )
niks3-go-unit-tests> 	        AND NOT EXISTS (
niks3-go-unit-tests> 	            SELECT 1
niks3-go-unit-tests> 	            FROM pending_objects AS po
niks3-go-unit-tests> 	            WHERE po.key = o.key
niks3-go-unit-tests> 	        )
niks3-go-unit-tests> 	        AND o.deleted_at IS NULL  -- Only mark fresh objects
niks3-go-unit-tests> 	    ORDER BY o.key COLLATE "C"  -- lock in key order, like commit_pending_closure
niks3-go-unit-tests> 	    FOR UPDATE
niks3-go-unit-tests> 	)
niks3-go-unit-tests> 	UPDATE objects
niks3-go-unit-tests> 	SET
niks3-go-unit-tests> 	    deleted_at = ct.now,
niks3-go-unit-tests> 	    first_deleted_at = COALESCE(first_deleted_at, ct.now)
niks3-go-unit-tests> 	FROM stale_objects, ct
niks3-go-unit-tests> 	WHERE objects.key = stale_objects.key
niks3-go-unit-tests> 	
niks3-go-unit-tests> 2026/10/08 12:59:54 WARN Request failed, retrying attempt=4 backoff=732.829579ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config
niks3-go-unit-tests> --- PASS: TestPendingClosureWriteTimeout (0.00s)
niks3-go-unit-tests>     --- PASS: TestPendingClosureWriteTimeout/empty (0.00s)
niks3-go-unit-tests>     --- PASS: TestPendingClosureWriteTimeout/670k_objects (0.00s)
niks3-go-unit-tests>     --- PASS: TestPendingClosureWriteTimeout/400_objects (0.00s)
niks3-go-unit-tests>     --- PASS: TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline (6.65s)
niks3-go-unit-tests> 2026/10/08 12:59:54 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=3 objects_failed=0
niks3-go-unit-tests> === NAME  TestClientIntegration
niks3-go-unit-tests>     client_integration_test.go:323: Objects in database after GC:
niks3-go-unit-tests>     client_integration_test.go:323: Successfully deleted all objects with GC --force
niks3-go-unit-tests> 2026/10/08 12:59:54 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:54 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:54 ERROR failed to remove object object=nar/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.nar.zst error="Post \"http://localhost:53104/bucket96/?delete=\": context canceled"
niks3-go-unit-tests> --- PASS: TestGCEndsOnShutdown (0.00s)
niks3-go-unit-tests>     --- PASS: TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark (5.54s)
niks3-go-unit-tests>     --- PASS: TestGCEndsOnShutdown/before_the_run (5.88s)
niks3-go-unit-tests>     --- PASS: TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete (6.17s)
niks3-go-unit-tests> --- PASS: TestClientIntegration (9.16s)
niks3-go-unit-tests> 2026/10/08 12:59:54 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:54 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:55 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:55 WARN Request failed, retrying attempt=5 backoff=1.4456844s error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=1 objects-deleted-after-grace-period=1 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Vacuumed table table=objects
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Received uploads request method=POST path=/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Aborted multipart uploads count=0 kept=0
niks3-go-unit-tests> 2026/10/08 12:59:55 WARN Force mode enabled - objects will be deleted immediately without grace period
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Vacuumed table table=pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Vacuumed table table=pending_objects
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Vacuumed table table=multipart_uploads
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Vacuumed table table=closures
niks3-go-unit-tests> 2026/10/08 12:59:55 INFO Vacuumed table table=objects
niks3-go-unit-tests> --- PASS: TestForceGCDuringPushOffersSweptObject (0.03s)
niks3-go-unit-tests>     --- PASS: TestForceGCDuringPushOffersSweptObject/before_pending_rows (6.23s)
niks3-go-unit-tests>     --- PASS: TestForceGCDuringPushOffersSweptObject/after_presence_check (5.99s)
niks3-go-unit-tests> 2026/10/08 12:59:55 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"
niks3-go-unit-tests> 2026/10/08 12:59:55 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"
niks3-go-unit-tests> 2026/10/08 12:59:56 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: sending request: request failed after retries: Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused"
niks3-go-unit-tests> 2026/10/08 12:59:56 WARN Request failed, retrying attempt=1 backoff=100ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config
niks3-go-unit-tests> 2026/10/08 12:59:56 WARN Request failed, retrying attempt=2 backoff=198.630803ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config
niks3-go-unit-tests> 2026/10/08 12:59:56 WARN Request failed, retrying attempt=3 backoff=360.214949ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config
niks3-go-unit-tests> 2026/10/08 12:59:57 WARN Request failed, retrying attempt=4 backoff=788.353878ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config
niks3-go-unit-tests> 2026/10/08 12:59:57 WARN Request failed, retrying attempt=5 backoff=1.51991643s error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config
niks3-go-unit-tests> 2026/10/08 12:59:59 WARN Request failed, retrying attempt=1 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:59 WARN Request failed, retrying attempt=2 backoff=202.974908ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures
niks3-go-unit-tests> 2026/10/08 12:59:59 WARN Request failed, retrying attempt=3 backoff=415.686687ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures
niks3-go-unit-tests> 2026/10/08 13:00:00 WARN Request failed, retrying attempt=4 backoff=729.794154ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures
niks3-go-unit-tests> 2026/10/08 13:00:00 WARN Request failed, retrying attempt=5 backoff=1.673739977s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures
niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)
niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (5.82s)
niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (5.56s)
niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (12.68s)
niks3-go-unit-tests> PASS
niks3-go-unit-tests> {"timestamp":"2026-10-08T13:00:02.646764Z","level":"ERROR","message":"HTTP transport failed","event":"http_transport_failed","component":"server","subsystem":"transport","peer_addr":"127.0.0.1:53348","error_kind":"io_error","error":"Cancelled","result":"transport_error","target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":2354,"threadName":"rustfs-worker","threadId":"ThreadId(9)"}
niks3-go-unit-tests> 2026-10-08 13:00:02.862 UTC [45012] LOG:  received smart shutdown request
niks3-go-unit-tests> 2026-10-08 13:00:02.863 UTC [45012] LOG:  background worker "logical replication launcher" (PID 45022) exited with exit code 1
niks3-go-unit-tests> 2026-10-08 13:00:04.190 UTC [45017] LOG:  checkpoint complete: wrote 10957 buffers (66.9%), wrote 5 SLRU buffers; 0 WAL file(s) added, 0 removed, 33 recycled; write=12.535 s, sync=0.678 s, total=13.313 s; sync files=34045, longest=0.001 s, average=0.001 s; distance=533150 kB, estimate=533150 kB; lsn=0/22824598, redo lsn=0/22034A98
niks3-go-unit-tests> 2026-10-08 13:00:04.190 UTC [45017] LOG:  shutting down
niks3-go-unit-tests> 2026-10-08 13:00:04.190 UTC [45017] LOG:  checkpoint starting: shutdown immediate
niks3-go-unit-tests> 2026-10-08 13:00:04.290 UTC [45017] LOG:  checkpoint complete: wrote 1352 buffers (8.3%), wrote 1 SLRU buffers; 0 WAL file(s) added, 0 removed, 0 recycled; write=0.077 s, sync=0.022 s, total=0.100 s; sync files=490, longest=0.004 s, average=0.001 s; distance=8126 kB, estimate=480648 kB; lsn=0/22824610, redo lsn=0/22824610
niks3-go-unit-tests> 2026-10-08 13:00:04.296 UTC [45012] LOG:  database system is shut down
niks3-go-unit-tests> Running OIDC tests...
niks3-go-unit-tests> === RUN   TestAudienceForIssuer
niks3-go-unit-tests> === PAUSE TestAudienceForIssuer
niks3-go-unit-tests> === RUN   TestGlobMatch
niks3-go-unit-tests> === PAUSE TestGlobMatch
niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken
niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken
niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience
niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience
niks3-go-unit-tests> === RUN   TestValidateToken_Expired
niks3-go-unit-tests> === PAUSE TestValidateToken_Expired
niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch
niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch
niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch
niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch
niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders
niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders
niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider
niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider
niks3-go-unit-tests> === RUN   TestValidateToken_KubernetesServiceAccount
niks3-go-unit-tests> === PAUSE TestValidateToken_KubernetesServiceAccount
niks3-go-unit-tests> === RUN   TestNewValidator_KubernetesRequiresCA
niks3-go-unit-tests> === PAUSE TestNewValidator_KubernetesRequiresCA
niks3-go-unit-tests> === RUN   TestValidateToken_KubernetesIssuerFromOwnToken
niks3-go-unit-tests> === PAUSE TestValidateToken_KubernetesIssuerFromOwnToken
niks3-go-unit-tests> === RUN   TestPins_ReservedForMatchingRule
niks3-go-unit-tests> === PAUSE TestPins_ReservedForMatchingRule
niks3-go-unit-tests> === RUN   TestPins_TopLevelShorthand
niks3-go-unit-tests> === PAUSE TestPins_TopLevelShorthand
niks3-go-unit-tests> === RUN   TestPins_ConfigValidation
niks3-go-unit-tests> === PAUSE TestPins_ConfigValidation
niks3-go-unit-tests> === RUN   TestScopes_LegacyProviderDefaultsToWrite
niks3-go-unit-tests> === PAUSE TestScopes_LegacyProviderDefaultsToWrite
niks3-go-unit-tests> === RUN   TestScopes_Rules
niks3-go-unit-tests> === PAUSE TestScopes_Rules
niks3-go-unit-tests> === RUN   TestScopes_ConfigValidation
niks3-go-unit-tests> === PAUSE TestScopes_ConfigValidation
niks3-go-unit-tests> === CONT  TestAudienceForIssuer
niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)
niks3-go-unit-tests> === CONT  TestValidateToken_Expired
niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch
niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch
niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders
niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider
niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken
niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience
niks3-go-unit-tests> === CONT  TestGlobMatch
niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo
niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo
niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar
niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar
niks3-go-unit-tests> === RUN   TestGlobMatch/*_
niks3-go-unit-tests> === PAUSE TestGlobMatch/*_
niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything
niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything
niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo
niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo
niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar
niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar
niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar
niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar
niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar
niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar
niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar
niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar
niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo
niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo
niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar
niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar
niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar
niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar
niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz
niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz
niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar
niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar
niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo
niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo
niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main
niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main
niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.0
niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.0
niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main
niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main
niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo
niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo
niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo
niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo
niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo
niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo
niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo
niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo
niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo
niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo
niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main
niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main
niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main
niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main
niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo
niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main
niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main
niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo
niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo
niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo
niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo
niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo
niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main
niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.0
niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main
niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo
niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar
niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz
niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar
niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar
niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo
niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar
niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar
niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar
niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar
niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo
niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything
niks3-go-unit-tests> === CONT  TestGlobMatch/*_
niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar
niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)
niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)
niks3-go-unit-tests> === CONT  TestValidateToken_KubernetesServiceAccount
niks3-go-unit-tests> === CONT  TestScopes_ConfigValidation
niks3-go-unit-tests> === CONT  TestScopes_Rules
niks3-go-unit-tests> === CONT  TestScopes_LegacyProviderDefaultsToWrite
niks3-go-unit-tests> --- PASS: TestScopes_ConfigValidation (0.00s)
niks3-go-unit-tests> === CONT  TestPins_ConfigValidation
niks3-go-unit-tests> === CONT  TestPins_TopLevelShorthand
niks3-go-unit-tests> --- PASS: TestPins_ConfigValidation (0.00s)
niks3-go-unit-tests> === CONT  TestPins_ReservedForMatchingRule
niks3-go-unit-tests> === CONT  TestValidateToken_KubernetesIssuerFromOwnToken
niks3-go-unit-tests> === CONT  TestNewValidator_KubernetesRequiresCA
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53777/oidc
niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.04s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53779/oidc
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53781/oidc
niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.09s)
niks3-go-unit-tests> --- PASS: TestScopes_Rules (0.08s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53785/oidc
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53788/oidc
niks3-go-unit-tests> --- PASS: TestScopes_LegacyProviderDefaultsToWrite (0.09s)
niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.10s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53790/oidc
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53792/oidc
niks3-go-unit-tests> 2026/10/08 13:00:06 http: TLS handshake error from 127.0.0.1:53786: remote error: tls: bad certificate
niks3-go-unit-tests> --- PASS: TestNewValidator_KubernetesRequiresCA (0.09s)
niks3-go-unit-tests> --- PASS: TestPins_ReservedForMatchingRule (0.10s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=kubernetes issuer=https://127.0.0.1:53794
niks3-go-unit-tests> --- PASS: TestPins_TopLevelShorthand (0.10s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53797/oidc
niks3-go-unit-tests> --- PASS: TestValidateToken_KubernetesServiceAccount (0.11s)
niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.11s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=kubernetes issuer=https://oidc.eks.invalid/id/ABC123
niks3-go-unit-tests> --- PASS: TestValidateToken_KubernetesIssuerFromOwnToken (0.11s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:53803/oidc
niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.13s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=provider1 issuer=http://127.0.0.1:53783/oidc
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=provider2 issuer=http://127.0.0.1:53805/oidc
niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.16s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO OIDC provider initialized name=provider1 issuer=http://127.0.0.1:53800/oidc
niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.19s)
niks3-go-unit-tests> PASS
niks3-go-unit-tests> Running hook tests...
niks3-go-unit-tests> === RUN   TestSendPathsEmpty
niks3-go-unit-tests> === PAUSE TestSendPathsEmpty
niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch
niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch
niks3-go-unit-tests> === RUN   TestQueueDeduplication
niks3-go-unit-tests> === PAUSE TestQueueDeduplication
niks3-go-unit-tests> === RUN   TestQueueRemove
niks3-go-unit-tests> === PAUSE TestQueueRemove
niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit
niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit
niks3-go-unit-tests> === RUN   TestQueueRetryMovesToBack
niks3-go-unit-tests> === PAUSE TestQueueRetryMovesToBack
niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle
niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle
niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters
niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters
niks3-go-unit-tests> === RUN   TestQueueRemoveLargeClosure
niks3-go-unit-tests> === PAUSE TestQueueRemoveLargeClosure
niks3-go-unit-tests> === RUN   TestServerClientIntegration
niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)
niks3-go-unit-tests> === RUN   TestServerQueueError
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Failed to queue paths error="permission denied" count=1
niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)
niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation
niks3-go-unit-tests>     server_test.go:146: systemd socket activation does not exist on darwin
niks3-go-unit-tests> --- SKIP: TestGetListenerSocketActivation (0.00s)
niks3-go-unit-tests> === RUN   TestDrainIsolatesPoisonPath
niks3-go-unit-tests> === PAUSE TestDrainIsolatesPoisonPath
niks3-go-unit-tests> === RUN   TestRunNotBlockedByPoisonHead
niks3-go-unit-tests> === PAUSE TestRunNotBlockedByPoisonHead
niks3-go-unit-tests> === RUN   TestDrainGivesUpWhenServerDown
niks3-go-unit-tests> === PAUSE TestDrainGivesUpWhenServerDown
niks3-go-unit-tests> === RUN   TestFailedPathPrunedByLaterClosure
niks3-go-unit-tests> === PAUSE TestFailedPathPrunedByLaterClosure
niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves
niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves
niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths
niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths
niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps
niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps
niks3-go-unit-tests> === RUN   TestDrainTimeout
niks3-go-unit-tests> === PAUSE TestDrainTimeout
niks3-go-unit-tests> === CONT  TestSendPathsEmpty
niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)
niks3-go-unit-tests> === CONT  TestDrainTimeout
niks3-go-unit-tests> === CONT  TestQueueRemoveLargeClosure
niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters
niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle
niks3-go-unit-tests> === CONT  TestQueueRetryMovesToBack
niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit
niks3-go-unit-tests> === CONT  TestQueueRemove
niks3-go-unit-tests> === CONT  TestQueueDeduplication
niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch
niks3-go-unit-tests> === CONT  TestDrainIsolatesPoisonPath
niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves
niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps
niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths
niks3-go-unit-tests> === CONT  TestDrainGivesUpWhenServerDown
niks3-go-unit-tests> === CONT  TestFailedPathPrunedByLaterClosure
niks3-go-unit-tests> === CONT  TestRunNotBlockedByPoisonHead
niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Upload queue status pending=2
niks3-go-unit-tests> 2026/10/08 13:00:06 WARN Store path no longer exists (garbage collected?), removing from queue path=/private/var/tb/8/scratch/f058402f878f26dd/build/TestWorkerSkipsGCdPaths798883759/002/nonexistent
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=2
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Upload queue status pending=2
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=1
niks3-go-unit-tests> --- PASS: TestQueueRetryMovesToBack (0.01s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=4
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed error="upload failed" count=4
niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.01s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Upload queue status pending=2
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed error="upload failed" count=1
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=2
niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.01s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=2
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed error="upload failed" count=2
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed, will retry later error="upload failed" path=/private/var/tb/8/scratch/f058402f878f26dd/build/TestDrainGivesUpWhenServerDown883051586/002/a
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed, will retry later error="upload failed" path=/private/var/tb/8/scratch/f058402f878f26dd/build/TestDrainIsolatesPoisonPath836598520/002/bbb
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Upload queue status pending=3
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed error="upload failed" count=1
niks3-go-unit-tests> --- PASS: TestQueueRemove (0.02s)
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed, will retry later error="upload failed" path=/private/var/tb/8/scratch/f058402f878f26dd/build/TestDrainGivesUpWhenServerDown883051586/002/b
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=1
niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=2
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed error="upload failed" count=2
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed, will retry later error="upload failed" path=/private/var/tb/8/scratch/f058402f878f26dd/build/TestDrainGivesUpWhenServerDown883051586/002/c
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed error="upload failed" count=1
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed, will retry later error="upload failed" path=/private/var/tb/8/scratch/f058402f878f26dd/build/TestDrainGivesUpWhenServerDown883051586/002/d
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed error="upload failed" count=1
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=2
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed error="upload failed" count=2
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed, will retry later error="upload failed" path=/private/var/tb/8/scratch/f058402f878f26dd/build/TestDrainGivesUpWhenServerDown883051586/002/e
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed, will retry later error="upload failed" path=/private/var/tb/8/scratch/f058402f878f26dd/build/TestDrainGivesUpWhenServerDown883051586/002/f
niks3-go-unit-tests> 2026/10/08 13:00:06 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed error="upload failed" count=1
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Drain finished with paths left in queue remaining=10
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Drain finished with paths left in queue remaining=1
niks3-go-unit-tests> --- PASS: TestFailedPathPrunedByLaterClosure (0.02s)
niks3-go-unit-tests> --- PASS: TestDrainIsolatesPoisonPath (0.02s)
niks3-go-unit-tests> --- PASS: TestDrainGivesUpWhenServerDown (0.02s)
niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.03s)
niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.03s)
niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.03s)
niks3-go-unit-tests> --- PASS: TestQueueRemoveLargeClosure (0.05s)
niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.16s)
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Upload failed error="context deadline exceeded" count=2
niks3-go-unit-tests> 2026/10/08 13:00:06 ERROR Drain finished with paths left in queue remaining=4
niks3-go-unit-tests> --- PASS: TestDrainTimeout (0.22s)
niks3-go-unit-tests> 2026/10/08 13:00:07 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:07 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:07 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:07 ERROR Upload failed error="upload failed" count=1
niks3-go-unit-tests> 2026/10/08 13:00:07 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:07 ERROR Upload failed error="upload failed" count=1
niks3-go-unit-tests> 2026/10/08 13:00:07 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:07 ERROR Upload failed error="upload failed" count=1
niks3-go-unit-tests> 2026/10/08 13:00:07 INFO Uploading batch count=1
niks3-go-unit-tests> 2026/10/08 13:00:07 ERROR Upload failed error="upload failed" count=1
niks3-go-unit-tests> 2026/10/08 13:00:07 ERROR Drain finished with paths left in queue remaining=1
niks3-go-unit-tests> --- PASS: TestRunNotBlockedByPoisonHead (1.04s)
niks3-go-unit-tests> PASS
niks3-go-unit-tests> Running signing tests...
niks3-go-unit-tests> === RUN   TestExternalSigner
niks3-go-unit-tests> === PAUSE TestExternalSigner
niks3-go-unit-tests> === RUN   TestExternalSignerWrongPublicKey
niks3-go-unit-tests> === PAUSE TestExternalSignerWrongPublicKey
niks3-go-unit-tests> === RUN   TestExternalSignerErrors
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors
niks3-go-unit-tests> === RUN   TestExternalSignerRestartsAfterCrash
niks3-go-unit-tests> === PAUSE TestExternalSignerRestartsAfterCrash
niks3-go-unit-tests> === RUN   TestExternalSignerProgramErrorKeepsProcess
niks3-go-unit-tests> === PAUSE TestExternalSignerProgramErrorKeepsProcess
niks3-go-unit-tests> === RUN   TestExternalSignerClose
niks3-go-unit-tests> === PAUSE TestExternalSignerClose
niks3-go-unit-tests> === RUN   TestExternalSignerRejectsInvalidPublicKey
niks3-go-unit-tests> === PAUSE TestExternalSignerRejectsInvalidPublicKey
niks3-go-unit-tests> === RUN   TestExternalSignerCancellation
niks3-go-unit-tests> === PAUSE TestExternalSignerCancellation
niks3-go-unit-tests> === RUN   TestGenerateFingerprint
niks3-go-unit-tests> === PAUSE TestGenerateFingerprint
niks3-go-unit-tests> === RUN   TestParseSigningKey
niks3-go-unit-tests> === PAUSE TestParseSigningKey
niks3-go-unit-tests> === RUN   TestSign
niks3-go-unit-tests> === PAUSE TestSign
niks3-go-unit-tests> === RUN   TestSignBatch
niks3-go-unit-tests> === PAUSE TestSignBatch
niks3-go-unit-tests> === RUN   TestNilKeyPublicKey
niks3-go-unit-tests> === PAUSE TestNilKeyPublicKey
niks3-go-unit-tests> === RUN   TestSignErrors
niks3-go-unit-tests> === PAUSE TestSignErrors
niks3-go-unit-tests> === CONT  TestExternalSigner
niks3-go-unit-tests> === CONT  TestSignBatch
niks3-go-unit-tests> === CONT  TestExternalSignerCancellation
niks3-go-unit-tests> === CONT  TestExternalSignerProgramErrorKeepsProcess
niks3-go-unit-tests> === CONT  TestSign
niks3-go-unit-tests> === CONT  TestParseSigningKey
niks3-go-unit-tests> === RUN   TestParseSigningKey/valid_32-byte_key
niks3-go-unit-tests> === PAUSE TestParseSigningKey/valid_32-byte_key
niks3-go-unit-tests> === CONT  TestGenerateFingerprint
niks3-go-unit-tests> === RUN   TestGenerateFingerprint/basic_with_references
niks3-go-unit-tests> === CONT  TestExternalSignerErrors
niks3-go-unit-tests> === CONT  TestExternalSignerRestartsAfterCrash
niks3-go-unit-tests> === CONT  TestExternalSignerRejectsInvalidPublicKey
niks3-go-unit-tests> === RUN   TestExternalSignerRejectsInvalidPublicKey/#00
niks3-go-unit-tests> === PAUSE TestExternalSignerRejectsInvalidPublicKey/#00
niks3-go-unit-tests> === RUN   TestExternalSignerRejectsInvalidPublicKey/no-colon
niks3-go-unit-tests> === PAUSE TestExternalSignerRejectsInvalidPublicKey/no-colon
niks3-go-unit-tests> === RUN   TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=
niks3-go-unit-tests> === PAUSE TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=
niks3-go-unit-tests> === RUN   TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!
niks3-go-unit-tests> === PAUSE TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!
niks3-go-unit-tests> === CONT  TestSignErrors
niks3-go-unit-tests> === CONT  TestNilKeyPublicKey
niks3-go-unit-tests> === CONT  TestExternalSignerWrongPublicKey
niks3-go-unit-tests> === CONT  TestExternalSignerClose
niks3-go-unit-tests> === RUN   TestParseSigningKey/valid_32-byte_key_with_different_name
niks3-go-unit-tests> === PAUSE TestParseSigningKey/valid_32-byte_key_with_different_name
niks3-go-unit-tests> === RUN   TestParseSigningKey/no_colon
niks3-go-unit-tests> === PAUSE TestParseSigningKey/no_colon
niks3-go-unit-tests> === RUN   TestParseSigningKey/empty_name
niks3-go-unit-tests> === PAUSE TestParseSigningKey/empty_name
niks3-go-unit-tests> === RUN   TestParseSigningKey/invalid_base64
niks3-go-unit-tests> === PAUSE TestParseSigningKey/invalid_base64
niks3-go-unit-tests> === RUN   TestParseSigningKey/wrong_length
niks3-go-unit-tests> === PAUSE TestParseSigningKey/wrong_length
niks3-go-unit-tests> === CONT  TestParseSigningKey/valid_32-byte_key
niks3-go-unit-tests> === PAUSE TestGenerateFingerprint/basic_with_references
niks3-go-unit-tests> === RUN   TestGenerateFingerprint/no_references
niks3-go-unit-tests> === CONT  TestParseSigningKey/empty_name
niks3-go-unit-tests> === RUN   TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==
niks3-go-unit-tests> === PAUSE TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==
niks3-go-unit-tests> === CONT  TestExternalSignerRejectsInvalidPublicKey/#00
niks3-go-unit-tests> === CONT  TestParseSigningKey/wrong_length
niks3-go-unit-tests> === CONT  TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=
niks3-go-unit-tests> --- PASS: TestNilKeyPublicKey (0.00s)
niks3-go-unit-tests> === CONT  TestParseSigningKey/invalid_base64
niks3-go-unit-tests> === CONT  TestParseSigningKey/valid_32-byte_key_with_different_name
niks3-go-unit-tests> === CONT  TestParseSigningKey/no_colon
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/wrong_shape
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/wrong_shape
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/wrong_id
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/wrong_id
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/missing_signatures
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/missing_signatures
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/unrequested_key
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/unrequested_key
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/invalid_signature_base64
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/invalid_signature_base64
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/exit_at_startup
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/exit_at_startup
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/exit_on_request
niks3-go-unit-tests> === PAUSE TestGenerateFingerprint/no_references
niks3-go-unit-tests> === CONT  TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!
niks3-go-unit-tests> === CONT  TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==
niks3-go-unit-tests> === CONT  TestExternalSignerRejectsInvalidPublicKey/no-colon
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/exit_on_request
niks3-go-unit-tests> === RUN   TestGenerateFingerprint/unsorted_references_get_sorted
niks3-go-unit-tests> === PAUSE TestGenerateFingerprint/unsorted_references_get_sorted
niks3-go-unit-tests> --- PASS: TestExternalSignerRejectsInvalidPublicKey (0.00s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerRejectsInvalidPublicKey/#00 (0.00s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA= (0.00s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerRejectsInvalidPublicKey/test-key:invalid! (0.00s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerRejectsInvalidPublicKey/test-key:YQ== (0.00s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerRejectsInvalidPublicKey/no-colon (0.00s)
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/null_signatures
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/null_signatures
niks3-go-unit-tests> === RUN   TestGenerateFingerprint/invalid_nar_hash_prefix
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/too_few_signatures
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/too_few_signatures
niks3-go-unit-tests> === PAUSE TestGenerateFingerprint/invalid_nar_hash_prefix
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/missing_signature_name
niks3-go-unit-tests> === RUN   TestGenerateFingerprint/invalid_nar_hash_length
niks3-go-unit-tests> === PAUSE TestGenerateFingerprint/invalid_nar_hash_length
niks3-go-unit-tests> === RUN   TestGenerateFingerprint/invalid_store_path_prefix
niks3-go-unit-tests> === PAUSE TestGenerateFingerprint/invalid_store_path_prefix
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/missing_signature_name
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/program_error
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/program_error
niks3-go-unit-tests> === RUN   TestGenerateFingerprint/invalid_reference_prefix
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/invalid_JSON
niks3-go-unit-tests> === PAUSE TestGenerateFingerprint/invalid_reference_prefix
niks3-go-unit-tests> === CONT  TestGenerateFingerprint/basic_with_references
niks3-go-unit-tests> === CONT  TestGenerateFingerprint/invalid_nar_hash_length
niks3-go-unit-tests> === CONT  TestGenerateFingerprint/invalid_reference_prefix
niks3-go-unit-tests> === CONT  TestGenerateFingerprint/no_references
niks3-go-unit-tests> === CONT  TestGenerateFingerprint/unsorted_references_get_sorted
niks3-go-unit-tests> === CONT  TestGenerateFingerprint/invalid_nar_hash_prefix
niks3-go-unit-tests> === CONT  TestGenerateFingerprint/invalid_store_path_prefix
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/invalid_JSON
niks3-go-unit-tests> --- PASS: TestGenerateFingerprint (0.00s)
niks3-go-unit-tests>     --- PASS: TestGenerateFingerprint/basic_with_references (0.00s)
niks3-go-unit-tests>     --- PASS: TestGenerateFingerprint/invalid_nar_hash_length (0.00s)
niks3-go-unit-tests>     --- PASS: TestGenerateFingerprint/invalid_reference_prefix (0.00s)
niks3-go-unit-tests>     --- PASS: TestGenerateFingerprint/no_references (0.00s)
niks3-go-unit-tests>     --- PASS: TestGenerateFingerprint/invalid_nar_hash_prefix (0.00s)
niks3-go-unit-tests>     --- PASS: TestGenerateFingerprint/unsorted_references_get_sorted (0.00s)
niks3-go-unit-tests>     --- PASS: TestGenerateFingerprint/invalid_store_path_prefix (0.00s)
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/wrong_signature_name
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/wrong_signature_name
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/wrong_signature_length
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/wrong_signature_length
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/null
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/null
niks3-go-unit-tests> === RUN   TestExternalSignerErrors/wrong_signatures_shape
niks3-go-unit-tests> --- PASS: TestSign (0.00s)
niks3-go-unit-tests> === PAUSE TestExternalSignerErrors/wrong_signatures_shape
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/wrong_shape
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/too_few_signatures
niks3-go-unit-tests> === RUN   TestSignErrors/nil_key
niks3-go-unit-tests> === PAUSE TestSignErrors/nil_key
niks3-go-unit-tests> === RUN   TestSignErrors/nil_narinfo
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/wrong_signature_name
niks3-go-unit-tests> --- PASS: TestParseSigningKey (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSigningKey/empty_name (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSigningKey/wrong_length (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSigningKey/invalid_base64 (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSigningKey/no_colon (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSigningKey/valid_32-byte_key (0.00s)
niks3-go-unit-tests>     --- PASS: TestParseSigningKey/valid_32-byte_key_with_different_name (0.00s)
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/null_signatures
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/wrong_signatures_shape
niks3-go-unit-tests> --- PASS: TestSignBatch (0.01s)
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/wrong_signature_length
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/missing_signatures
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/wrong_id
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/unrequested_key
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/invalid_JSON
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/program_error
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/missing_signature_name
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/invalid_signature_base64
niks3-go-unit-tests> === PAUSE TestSignErrors/nil_narinfo
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/null
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/exit_at_startup
niks3-go-unit-tests> === RUN   TestSignErrors/invalid_narinfo
niks3-go-unit-tests> === PAUSE TestSignErrors/invalid_narinfo
niks3-go-unit-tests> === RUN   TestSignErrors/canceled
niks3-go-unit-tests> === PAUSE TestSignErrors/canceled
niks3-go-unit-tests> === CONT  TestSignErrors/nil_key
niks3-go-unit-tests> === CONT  TestSignErrors/canceled
niks3-go-unit-tests> === CONT  TestSignErrors/nil_narinfo
niks3-go-unit-tests> === CONT  TestExternalSignerErrors/exit_on_request
niks3-go-unit-tests> === CONT  TestSignErrors/invalid_narinfo
niks3-go-unit-tests> --- PASS: TestSignErrors (0.01s)
niks3-go-unit-tests>     --- PASS: TestSignErrors/nil_key (0.00s)
niks3-go-unit-tests>     --- PASS: TestSignErrors/canceled (0.00s)
niks3-go-unit-tests>     --- PASS: TestSignErrors/nil_narinfo (0.00s)
niks3-go-unit-tests>     --- PASS: TestSignErrors/invalid_narinfo (0.00s)
niks3-go-unit-tests> --- PASS: TestExternalSignerCancellation (0.11s)
niks3-go-unit-tests> --- PASS: TestExternalSigner (0.18s)
niks3-go-unit-tests> --- PASS: TestExternalSignerWrongPublicKey (0.23s)
niks3-go-unit-tests> --- PASS: TestExternalSignerProgramErrorKeepsProcess (0.30s)
niks3-go-unit-tests> --- PASS: TestExternalSignerClose (0.37s)
niks3-go-unit-tests> --- PASS: TestExternalSignerRestartsAfterCrash (0.88s)
niks3-go-unit-tests> /private/var/tb/8/scratch/f058402f878f26dd/build/TestExternalSignerRestartsAfterCrash3493695141/001/sign program: line 7: /private/var/tb/8/scratch/f058402f878f26dd/build/TestExternalSignerRestartsAfterCrash3493695141/001/sign program.starts: No such file or directory
niks3-go-unit-tests> --- PASS: TestExternalSignerErrors (0.00s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/exit_on_request (0.44s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/missing_signatures (0.51s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/wrong_shape (0.58s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/wrong_id (0.65s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/wrong_signature_name (0.72s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/too_few_signatures (0.79s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/null (0.93s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/invalid_signature_base64 (1.00s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/program_error (1.07s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/wrong_signature_length (1.14s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/invalid_JSON (1.21s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/exit_at_startup (1.28s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/null_signatures (1.35s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/wrong_signatures_shape (1.42s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/missing_signature_name (1.49s)
niks3-go-unit-tests>     --- PASS: TestExternalSignerErrors/unrequested_key (1.57s)
niks3-go-unit-tests> PASS
niks3-go-unit-tests> Running OpenBao signer tests...
niks3-go-unit-tests> === RUN   TestSign
niks3-go-unit-tests> === PAUSE TestSign
niks3-go-unit-tests> === RUN   TestSignErrors
niks3-go-unit-tests> === PAUSE TestSignErrors
niks3-go-unit-tests> === RUN   TestServe
niks3-go-unit-tests> === PAUSE TestServe
niks3-go-unit-tests> === RUN   TestServeRejectsGarbage
niks3-go-unit-tests> === PAUSE TestServeRejectsGarbage
niks3-go-unit-tests> === CONT  TestSign
niks3-go-unit-tests> === CONT  TestServe
niks3-go-unit-tests> === CONT  TestServeRejectsGarbage
niks3-go-unit-tests> --- PASS: TestServeRejectsGarbage (0.00s)
niks3-go-unit-tests> === CONT  TestSignErrors
niks3-go-unit-tests> === RUN   TestSignErrors/http_error
niks3-go-unit-tests> === PAUSE TestSignErrors/http_error
niks3-go-unit-tests> === RUN   TestSignErrors/invalid_json
niks3-go-unit-tests> === PAUSE TestSignErrors/invalid_json
niks3-go-unit-tests> === RUN   TestSignErrors/no_prefix
niks3-go-unit-tests> === PAUSE TestSignErrors/no_prefix
niks3-go-unit-tests> === RUN   TestSignErrors/invalid_base64
niks3-go-unit-tests> === PAUSE TestSignErrors/invalid_base64
niks3-go-unit-tests> === CONT  TestSignErrors/http_error
niks3-go-unit-tests> === CONT  TestSignErrors/no_prefix
niks3-go-unit-tests> === CONT  TestSignErrors/invalid_base64
niks3-go-unit-tests> === CONT  TestSignErrors/invalid_json
niks3-go-unit-tests> --- PASS: TestServe (0.00s)
niks3-go-unit-tests> --- PASS: TestSign (0.00s)
niks3-go-unit-tests> --- PASS: TestSignErrors (0.00s)
niks3-go-unit-tests>     --- PASS: TestSignErrors/invalid_json (0.00s)
niks3-go-unit-tests>     --- PASS: TestSignErrors/no_prefix (0.00s)
niks3-go-unit-tests>     --- PASS: TestSignErrors/http_error (0.00s)
niks3-go-unit-tests>     --- PASS: TestSignErrors/invalid_base64 (0.00s)
niks3-go-unit-tests> PASS

post-build step Upload coverage to codecov: ok
Skipping codecov: project=Mic92/niks3 attr=checks.aarch64-darwin.go-unit-tests