nixbot

1this derivation will be built:2  /nix/store/phvzgh6qw4nyqpj411bnc1sqs26waz43-niks3-go-unit-tests.drv3building '/nix/store/phvzgh6qw4nyqpj411bnc1sqs26waz43-niks3-go-unit-tests.drv' on 'ssh-ng://customer@mac02'4building '/nix/store/phvzgh6qw4nyqpj411bnc1sqs26waz43-niks3-go-unit-tests.drv'5niks3-go-unit-tests> Running client tests...6niks3-go-unit-tests> === RUN   TestDoServerRequestAttachesToken7niks3-go-unit-tests> === PAUSE TestDoServerRequestAttachesToken8niks3-go-unit-tests> === RUN   TestCaseHackSuffix9niks3-go-unit-tests> === PAUSE TestCaseHackSuffix10niks3-go-unit-tests> === RUN   TestPartSizeForNAR11niks3-go-unit-tests> === PAUSE TestPartSizeForNAR12niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer13niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer14niks3-go-unit-tests> === RUN   TestDumpPathMatchesNix15niks3-go-unit-tests> === PAUSE TestDumpPathMatchesNix16niks3-go-unit-tests> === RUN   TestDumpPathSingleFile17niks3-go-unit-tests> === PAUSE TestDumpPathSingleFile18niks3-go-unit-tests> === RUN   TestDumpPathWriterError19niks3-go-unit-tests> === PAUSE TestDumpPathWriterError20niks3-go-unit-tests> === RUN   TestEncodeNixBase3221niks3-go-unit-tests> === PAUSE TestEncodeNixBase3222niks3-go-unit-tests> === RUN   TestEncodeNixBase32WithRealHash23niks3-go-unit-tests> === PAUSE TestEncodeNixBase32WithRealHash24niks3-go-unit-tests> === RUN   TestConvertHashToNix3225niks3-go-unit-tests> === PAUSE TestConvertHashToNix3226niks3-go-unit-tests> === RUN   TestGetStorePathHash27niks3-go-unit-tests> === PAUSE TestGetStorePathHash28niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility29niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility30niks3-go-unit-tests> === RUN   TestParsePathInfoJSON31niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON32niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths33niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths34niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility35niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility36niks3-go-unit-tests> === RUN   TestRateLimiterFeedback37niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback38niks3-go-unit-tests> === RUN   TestRateLimiterFeedback_400DoesNotCountAsSuccess39niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess40niks3-go-unit-tests> === RUN   TestResolveStorePath41niks3-go-unit-tests> === PAUSE TestResolveStorePath42niks3-go-unit-tests> === RUN   TestDoWithRetry_BodyReplayedViaGetBody43niks3-go-unit-tests> === PAUSE TestDoWithRetry_BodyReplayedViaGetBody44niks3-go-unit-tests> === RUN   TestShellSplit45niks3-go-unit-tests> === PAUSE TestShellSplit46niks3-go-unit-tests> === RUN   TestShellSplitErrors47niks3-go-unit-tests> === PAUSE TestShellSplitErrors48niks3-go-unit-tests> === RUN   TestSetClientTLS49niks3-go-unit-tests> === PAUSE TestSetClientTLS50niks3-go-unit-tests> === RUN   TestSetClientTLSDoesNotMutateDefaultTransport51niks3-go-unit-tests> === PAUSE TestSetClientTLSDoesNotMutateDefaultTransport52niks3-go-unit-tests> === RUN   TestSetClientTLSErrors53niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors54niks3-go-unit-tests> === RUN   TestStaticToken55niks3-go-unit-tests> === PAUSE TestStaticToken56niks3-go-unit-tests> === RUN   TestFileTokenReadsAndCaches57niks3-go-unit-tests> === PAUSE TestFileTokenReadsAndCaches58niks3-go-unit-tests> === RUN   TestFileTokenMissing59niks3-go-unit-tests> === PAUSE TestFileTokenMissing60niks3-go-unit-tests> === RUN   TestFileTokenEmpty61niks3-go-unit-tests> === PAUSE TestFileTokenEmpty62niks3-go-unit-tests> === RUN   TestScriptTokenNoExpiryRerunsEveryCall63niks3-go-unit-tests> === PAUSE TestScriptTokenNoExpiryRerunsEveryCall64niks3-go-unit-tests> === RUN   TestScriptTokenCachesUntilRefresh65niks3-go-unit-tests> === PAUSE TestScriptTokenCachesUntilRefresh66niks3-go-unit-tests> === RUN   TestScriptTokenEmptyToken67niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyToken68niks3-go-unit-tests> === RUN   TestScriptTokenBadJSON69niks3-go-unit-tests> === PAUSE TestScriptTokenBadJSON70niks3-go-unit-tests> === RUN   TestScriptTokenScriptFails71niks3-go-unit-tests> === PAUSE TestScriptTokenScriptFails72niks3-go-unit-tests> === RUN   TestScriptTokenEmptyCommand73niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyCommand74niks3-go-unit-tests> === CONT  TestDoServerRequestAttachesToken75niks3-go-unit-tests> === CONT  TestResolveStorePath76niks3-go-unit-tests> === CONT  TestConvertHashToNix3277niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix3278niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix3279niks3-go-unit-tests> === CONT  TestDumpPathSingleFile80niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format81niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess82niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer83niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists84niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand85niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)86niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails87niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists88niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing89niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing90niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists91niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON92niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter enabled after throttle name=server-test rate=593niks3-go-unit-tests> === CONT  TestPartSizeForNAR94niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum95niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum96niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum97niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum98niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum99niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum100niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts101niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts102niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB103niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB104niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object105niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object106niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB107niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB108niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum109niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB110niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object111niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB112niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts113niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum114niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum115niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.00s)116niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)117niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)118niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)119niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)120niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)121niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)122niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)123niks3-go-unit-tests> === CONT  TestCaseHackSuffix124niks3-go-unit-tests> === CONT  TestRateLimiterFeedback125niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter126niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter127niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter128niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter129niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter130niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter131niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter132niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter133niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format134niks3-go-unit-tests> === CONT  TestStaticToken135niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)136niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken137niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format138niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format139niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh140niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall141niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.01s)142niks3-go-unit-tests> === CONT  TestFileTokenEmpty143niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.01s)144niks3-go-unit-tests> === CONT  TestFileTokenMissing145niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches146niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.01s)147niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)148niks3-go-unit-tests> === CONT  TestSetClientTLS149niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)150niks3-go-unit-tests> === CONT  TestSetClientTLSErrors151niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)152niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport153niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file154niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file155niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file156niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file157niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file158niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file159niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file160niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file161niks3-go-unit-tests> === CONT  TestEncodeNixBase32162niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash163niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash164niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input165niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input166niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash167niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)168niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter169niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter enabled after throttle name=server-test rate=5170niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.00s)171niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths172niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:52246173niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths174niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths175niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths176niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths177niks3-go-unit-tests> === CONT  TestParsePathInfoJSON178niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format179niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format180niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format181niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format182niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input183niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input184niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only185niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only186niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON187niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON188niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility189niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)190niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)191niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon192niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon193niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI194niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI195niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512196niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512197niks3-go-unit-tests> === CONT  TestGetStorePathHash198niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path199niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path200niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter backed off name=server-test rate=5201niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error202niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error203niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert204niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert205niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA206niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA207niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport208niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport209niks3-go-unit-tests> === CONT  TestDumpPathWriterError210niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error211niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility212niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field213niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field214niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text215niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text216niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive217niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error218niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error219niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive220niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error221niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing222niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text223niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text224niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method225niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method226niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix227niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)228niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)229niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)230niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter231niks3-go-unit-tests> === CONT  TestShellSplit232niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)233niks3-go-unit-tests> === CONT  TestShellSplitErrors234niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)235niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody236niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter enabled after throttle name=server-test rate=5237niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:52253238niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.02s)239niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter backed off name=server-test rate=5240niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter241niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:52253242niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.00s)243niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter244niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32245niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format246niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format247niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.01s)248niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)249niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)250niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)251niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file252niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash253niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter enabled after throttle name=server-test rate=5254niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file255niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:52257256niks3-go-unit-tests> 2026/06/28 13:40:12 WARN Rate limiter backed off name=server-test rate=5257niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.00s)258niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)259niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)260niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)261niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)262niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file263niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input264niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)265niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)266niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)267niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file268niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths269niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths270niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format271niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)272niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)273niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)274niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only275niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON276niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input277niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format278niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)279niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)280niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)281niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)282niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)283niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)284niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)285niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512286niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI287niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon288niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)289niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)290niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)291niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)292niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)293niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert294niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport295niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.00s)296niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)297niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)298niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)299niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)300niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA301niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.02s)302niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path303niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error304niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error305niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error306niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)307niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)308niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)309niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)310niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)311niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field312niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method313niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text314niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive315niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text316niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.00s)317niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)318niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)319niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)320niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)321niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)322niks3-go-unit-tests> 2026/06/28 13:40:12 http: TLS handshake error from 127.0.0.1:52259: remote error: tls: bad certificate323niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.00s)324niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)325niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)327niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.03s)328niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.04s)329niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.04s)330niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.07s)331niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.07s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.07s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)334niks3-go-unit-tests> PASS335niks3-go-unit-tests> Running server tests...336niks3-go-unit-tests> The files belonging to this database system will be owned by user "_nixbld1".337niks3-go-unit-tests> This user must also own the server process.338niks3-go-unit-tests> 339niks3-go-unit-tests> The database cluster will be initialized with locale "C".340niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".341niks3-go-unit-tests> The default text search configuration will be set to "english".342niks3-go-unit-tests> 343niks3-go-unit-tests> Data page checksums are disabled.344niks3-go-unit-tests> 345niks3-go-unit-tests> creating directory /nix/var/nix/builds/nix-63754-46525072/postgres1945796278/data ... ok346niks3-go-unit-tests> creating subdirectories ... ok347niks3-go-unit-tests> selecting dynamic shared memory implementation ... posix348niks3-go-unit-tests> selecting default "max_connections" ... 100349niks3-go-unit-tests> selecting default "shared_buffers" ... 128MB350niks3-go-unit-tests> selecting default time zone ... UTC351niks3-go-unit-tests> creating configuration files ... ok352niks3-go-unit-tests> running bootstrap script ... ok353niks3-go-unit-tests> performing post-bootstrap initialization ... ok354niks3-go-unit-tests> syncing data to disk ... ok355niks3-go-unit-tests> 356niks3-go-unit-tests> initdb: warning: enabling "trust" authentication for local connections357niks3-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.358niks3-go-unit-tests> 359niks3-go-unit-tests> Success. You can now start the database server using:360niks3-go-unit-tests> 361niks3-go-unit-tests>     pg_ctl -D /nix/var/nix/builds/nix-63754-46525072/postgres1945796278/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /nix/var/nix/builds/nix-63754-46525072/postgres1945796278:5432 - no response364niks3-go-unit-tests> 2026-06-28 13:40:13.794 UTC [63790] LOG:  starting PostgreSQL 17.10 on aarch64-apple-darwin25.4.0, compiled by clang version 21.1.8, 64-bit365niks3-go-unit-tests> 2026-06-28 13:40:13.794 UTC [63790] LOG:  listening on Unix socket "/nix/var/nix/builds/nix-63754-46525072/postgres1945796278/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-28 13:40:13.796 UTC [63794] LOG:  database system was shut down at 2026-06-28 13:40:13 UTC367niks3-go-unit-tests> 2026-06-28 13:40:13.797 UTC [63790] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /nix/var/nix/builds/nix-63754-46525072/postgres1945796278:5432 - accepting connections369niks3-go-unit-tests> <jemalloc>: option background_thread currently supports pthread only370niks3-go-unit-tests> {"timestamp":"2026-06-28T13:40:13.926268Z","level":"ERROR","fields":{"message":"list_path_raw: revjob err VolumeNotFound"},"target":"rustfs_ecstore::cache_value::metacache_set","filename":"crates/ecstore/src/cache_value/metacache_set.rs","line_number":486,"threadName":"rustfs-worker","threadId":"ThreadId(6)"}371niks3-go-unit-tests> === RUN   TestService_AuthMiddleware372niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware373niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader374niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader375niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects376niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects377niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware378niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware379niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC380niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC381niks3-go-unit-tests> === RUN   TestCacheConfigHandler382niks3-go-unit-tests> === PAUSE TestCacheConfigHandler383niks3-go-unit-tests> === RUN   TestCacheStatsHandler384niks3-go-unit-tests> === PAUSE TestCacheStatsHandler385niks3-go-unit-tests> === RUN   TestClientCADerivations386niks3-go-unit-tests> === PAUSE TestClientCADerivations387niks3-go-unit-tests> === RUN   TestClientErrorHandling388niks3-go-unit-tests> === PAUSE TestClientErrorHandling389niks3-go-unit-tests> === RUN   TestClientIntegration390niks3-go-unit-tests> === PAUSE TestClientIntegration391niks3-go-unit-tests> === RUN   TestClientMultipleUploads392niks3-go-unit-tests> === PAUSE TestClientMultipleUploads393niks3-go-unit-tests> === RUN   TestClientWithDependencies394niks3-go-unit-tests> === PAUSE TestClientWithDependencies395niks3-go-unit-tests> === RUN   TestPinProtectsFromGC396niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC397niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences398niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences399niks3-go-unit-tests> === RUN   TestGCMetrics400niks3-go-unit-tests> === PAUSE TestGCMetrics401niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew402niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew403niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams404niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams405niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams406niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams407niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty408niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty409niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest410niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest411niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask412niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask413niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates414niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates415niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail416niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail417niks3-go-unit-tests> === RUN   TestService_healthCheckHandler418niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler419niks3-go-unit-tests> === RUN   TestGenerateLandingPage420niks3-go-unit-tests> === PAUSE TestGenerateLandingPage421niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug422niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug423niks3-go-unit-tests> === RUN   TestMetricsInventory424niks3-go-unit-tests> === PAUSE TestMetricsInventory425niks3-go-unit-tests> === RUN   TestService_NativeMTLS426niks3-go-unit-tests> === PAUSE TestService_NativeMTLS427niks3-go-unit-tests> === RUN   TestServerTLSConfig428niks3-go-unit-tests> === PAUSE TestServerTLSConfig429niks3-go-unit-tests> === RUN   TestMultipartCleanup430niks3-go-unit-tests> === PAUSE TestMultipartCleanup431niks3-go-unit-tests> === RUN   TestObjectStatsTrigger432niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger433niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC434niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC435niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest436niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest437niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted438niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted439niks3-go-unit-tests> === RUN   TestParseSingleRange440niks3-go-unit-tests> === PAUSE TestParseSingleRange441niks3-go-unit-tests> === RUN   TestIsValidCachePath442niks3-go-unit-tests> === PAUSE TestIsValidCachePath443niks3-go-unit-tests> === RUN   TestReadProxyNarinfo444niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo445niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed446niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed447niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming448niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming449niks3-go-unit-tests> === RUN   TestReadProxy404450niks3-go-unit-tests> === PAUSE TestReadProxy404451niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath452niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath453niks3-go-unit-tests> === RUN   TestReadProxyHead454niks3-go-unit-tests> === PAUSE TestReadProxyHead455niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet456niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet457niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML458niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML459niks3-go-unit-tests> === RUN   TestReadProxyDisabled460niks3-go-unit-tests> === PAUSE TestReadProxyDisabled461niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest462niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest463niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload464niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload465niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists466niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists467niks3-go-unit-tests> === RUN   TestService_Rustfstest468niks3-go-unit-tests> === PAUSE TestService_Rustfstest469niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle470niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle471niks3-go-unit-tests> === RUN   TestProxyWriteTimeout472niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout473niks3-go-unit-tests> === RUN   TestIsValidUploadKey474niks3-go-unit-tests> === PAUSE TestIsValidUploadKey475niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys476niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys477niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody478niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody479niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler480niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler481niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler482niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler483niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity484niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity485niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered486niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered487niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT488niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT489niks3-go-unit-tests> === CONT  TestService_AuthMiddleware490niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler491niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered492niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT493niks3-go-unit-tests> === CONT  TestServerTLSConfig494niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA495niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA496niks3-go-unit-tests> === CONT  TestGCMetrics497niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file498niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file499niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file500niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file501niks3-go-unit-tests> === CONT  TestService_NativeMTLS502niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler503niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA504niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody505niks3-go-unit-tests> === CONT  TestMetricsInventory506niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug507niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure508niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure509niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart510niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart511niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts512niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts513niks3-go-unit-tests> === CONT  TestGenerateLandingPage514niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)515niks3-go-unit-tests> === CONT  TestService_healthCheckHandler516niks3-go-unit-tests> 2026-06-28 13:40:14.307 UTC [63863] ERROR:  relation "goose_db_version" does not exist at character 36517niks3-go-unit-tests> 2026-06-28 13:40:14.307 UTC [63863] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC518niks3-go-unit-tests> 2026-06-28 13:40:14.313 UTC [63864] ERROR:  relation "goose_db_version" does not exist at character 36519niks3-go-unit-tests> 2026-06-28 13:40:14.313 UTC [63864] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC520niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (25.02ms)521niks3-go-unit-tests> 2026-06-28 13:40:14.340 UTC [63865] ERROR:  relation "goose_db_version" does not exist at character 36522niks3-go-unit-tests> 2026-06-28 13:40:14.340 UTC [63865] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC523niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (772.13µs)524niks3-go-unit-tests> 2026-06-28 13:40:14.340 UTC [63868] ERROR:  relation "goose_db_version" does not exist at character 36525niks3-go-unit-tests> 2026-06-28 13:40:14.340 UTC [63868] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC526niks3-go-unit-tests> 2026-06-28 13:40:14.340 UTC [63867] ERROR:  relation "goose_db_version" does not exist at character 36527niks3-go-unit-tests> 2026-06-28 13:40:14.340 UTC [63867] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC528niks3-go-unit-tests> 2026-06-28 13:40:14.341 UTC [63869] ERROR:  relation "goose_db_version" does not exist at character 36529niks3-go-unit-tests> 2026-06-28 13:40:14.341 UTC [63869] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC530niks3-go-unit-tests> 2026-06-28 13:40:14.341 UTC [63866] ERROR:  relation "goose_db_version" does not exist at character 36531niks3-go-unit-tests> 2026-06-28 13:40:14.341 UTC [63866] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC532niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (7.45ms)533niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (2.01ms)534niks3-go-unit-tests> 2026-06-28 13:40:14.342 UTC [63870] ERROR:  relation "goose_db_version" does not exist at character 36535niks3-go-unit-tests> 2026-06-28 13:40:14.342 UTC [63870] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC536niks3-go-unit-tests> 2026-06-28 13:40:14.343 UTC [63872] ERROR:  relation "goose_db_version" does not exist at character 36537niks3-go-unit-tests> 2026-06-28 13:40:14.343 UTC [63872] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC538niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (801.63µs)539niks3-go-unit-tests> 2026-06-28 13:40:14.343 UTC [63871] ERROR:  relation "goose_db_version" does not exist at character 36540niks3-go-unit-tests> 2026-06-28 13:40:14.343 UTC [63871] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC541niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (1.67ms)542niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000543niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (1.31ms)544niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1.4ms)545niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (447.79µs)546niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2547niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (2.33ms)548niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000549niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received complete multipart upload request method=POST path=/api/multipart/complete550niks3-go-unit-tests> 2026/06/28 13:40:14 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst551niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.34s)552niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail553niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)554niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates555niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)556niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask557niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)558niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest559niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)560niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty561niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)562niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams563niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)564niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams565niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)566niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew567niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)568niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath569niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (5.35ms)570niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (7.32ms)571niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (8.62ms)572niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (8.19ms)573niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (384.96µs)574niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2575niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures576niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (12.81ms)577niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (13.05ms)578niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (22.77ms)579niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (13.18ms)580niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (20.77ms)581niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (21.84ms)582niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (20.72ms)583niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (959.54µs)584niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (564.83µs)585niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (488.83µs)586niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (710.58µs)587niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (1.81ms)588niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (1.74ms)589niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (2.58ms)590niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (1.45ms)591niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (1.34ms)592niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (1.87ms)593niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (1.19ms)594niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000595niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (1.29ms)596niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.35s)597niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys598niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info599niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info600niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal601niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal602niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key603niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key604niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key605niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key606niks3-go-unit-tests> === CONT  TestIsValidUploadKey607niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo608niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo609niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst610niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst611niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz612niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz613niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain614niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain615niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (21.64ms)616niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing617niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing618niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log619niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log620niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file621niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file622niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name623niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name624niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark625niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark626niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals627niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals628niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation629niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation630niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output631niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output632niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info633niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info634niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html635niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html636niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type637niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type638niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type639niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type640niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type641niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type642niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal643niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal644niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar645niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar646niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute647niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute648niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key649niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key650niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type651niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type652niks3-go-unit-tests> === CONT  TestProxyWriteTimeout653niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo654niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo655niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar656niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar657niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar658niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar659niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size660niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size661niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle662niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (1.61ms)663niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000664niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (1.45ms)665niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (2.01ms)666niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000667niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (2.83ms)668niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000669niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1.76ms)670niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (249.5µs)671niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2672niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (3.27ms)673niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000674niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (1.37ms)675niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (3.01ms)676niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000677niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (3.13ms)678niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000679niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (2.03ms)680niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (1.05ms)681niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000682niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1.41ms)683niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (758.75µs)684niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2685niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (2.75ms)686niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1.6ms)687niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (3.61ms)688niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (422.75µs)689niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2690niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1.88ms)691niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1ms)692niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (579.83µs)693niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2694niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (592.25µs)695niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2696niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (764.63µs)697niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2698niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (397.71µs)699niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2700niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (352.25µs)701niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2702niks3-go-unit-tests> 2026/06/28 13:40:14 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"703niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.36s)704niks3-go-unit-tests> === CONT  TestService_Rustfstest705niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures706niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures707niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures708niks3-go-unit-tests> 2026/06/28 13:40:14 WARN mTLS auth: subject not in bound subjects subject="CN=reader"709niks3-go-unit-tests> 2026/06/28 13:40:14 WARN mTLS auth: subject not in bound subjects subject="CN=writer"710niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.36s)711niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists712niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.33s)713niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload714niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Aborted multipart uploads count=0715niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received cleanup request method=DELETE path=/api/pending_closures716niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Aborted multipart uploads count=0717niks3-go-unit-tests> 2026/06/28 13:40:14 WARN Force mode enabled - objects will be deleted immediately without grace period718niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures719niks3-go-unit-tests> {"timestamp":"2026-06-28T13:40:14.38267Z","level":"ERROR","fields":{"message":"system path read failed","path_kind":"data_usage","operation":"read_primary","reason":"config_not_found","object":"buckets/.usage.json","error":"Config not found"},"target":"rustfs_ecstore::data_usage","filename":"crates/ecstore/src/data_usage.rs","line_number":126,"threadName":"rustfs-worker","threadId":"ThreadId(5)"}720niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Created nix-cache-info in bucket bucket=bucket6721niks3-go-unit-tests> 2026/06/28 13:40:14 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=0722niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=pending_closures723niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.37s)724niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest725niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=pending_objects726niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=multipart_uploads727niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=closures728niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=objects729niks3-go-unit-tests> --- PASS: TestGCMetrics (0.37s)730niks3-go-unit-tests> === CONT  TestReadProxyDisabled731niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received cleanup request method=DELETE path=/api/pending_closures732niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Aborted multipart uploads count=1733niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete734niks3-go-unit-tests> 2026-06-28 13:40:14.394 UTC [63865] ERROR:  Closure does not exist: id=1735niks3-go-unit-tests> 2026-06-28 13:40:14.394 UTC [63865] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE736niks3-go-unit-tests> 2026-06-28 13:40:14.394 UTC [63865] STATEMENT:  -- name: CommitPendingClosure :exec737niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)738niks3-go-unit-tests> 	739niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.38s)740niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML741niks3-go-unit-tests> 2026-06-28 13:40:14.435 UTC [63891] ERROR:  relation "goose_db_version" does not exist at character 36742niks3-go-unit-tests> 2026-06-28 13:40:14.435 UTC [63891] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC743niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug744niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /nix/var/nix/builds/nix-63754-46525072/TestNARDeduplicationMetadataUploadBug3192052892/001/store/xi0k4gyg643vdvyx7brr2ch81nl28p60-file1.txt745niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received complete multipart upload request method=POST path=/api/multipart/complete746niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (35.06ms)747niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (475.54µs)748niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=MjYwM2FjYmItODgxMC00ZGQyLWI3ZTEtZWE1YzgzZTc1NWQ2LjgxM2IwYjEwLTRkNmYtNGM5My1hYjE5LWE1MmE2NGE4MmY1MXgxNzgyNjU0MDE0MzgzODYxMDAw parts=10749niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete750niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (737.04µs)751niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (17.42ms)752niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000753niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Completed upload id=1754niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000755niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures756niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1.14ms)757niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Starting cleanup of old closures method=DELETE path=/api/closures758niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (631.46µs)759niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2760niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Aborted multipart uploads count=0761niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=2 objects-deleted-after-grace-period=0 objects-failed-to-delete=0762niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.20s)763niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet764niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=pending_closures765niks3-go-unit-tests> 2026-06-28 13:40:14.567 UTC [63899] ERROR:  relation "goose_db_version" does not exist at character 36766niks3-go-unit-tests> 2026-06-28 13:40:14.567 UTC [63899] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC767niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=pending_objects768niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=multipart_uploads769niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=closures770niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Vacuumed table table=objects771niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (9.53ms)772niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (379.88µs)773niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (1.59ms)774niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (2.04ms)775niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000776niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1.31ms)777niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (227.17µs)778niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2779niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures780niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures781niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000782niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.59s)783niks3-go-unit-tests> === CONT  TestReadProxyHead784niks3-go-unit-tests> 2026-06-28 13:40:14.602 UTC [63901] ERROR:  relation "goose_db_version" does not exist at character 36785niks3-go-unit-tests> 2026-06-28 13:40:14.602 UTC [63901] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC786niks3-go-unit-tests> 2026-06-28 13:40:14.602 UTC [63903] ERROR:  relation "goose_db_version" does not exist at character 36787niks3-go-unit-tests> 2026-06-28 13:40:14.602 UTC [63903] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC788niks3-go-unit-tests> 2026-06-28 13:40:14.602 UTC [63902] ERROR:  relation "goose_db_version" does not exist at character 36789niks3-go-unit-tests> 2026-06-28 13:40:14.602 UTC [63902] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC790niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)791niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Uploading xi0k4gyg643vdvyx7brr2ch81nl28p60-file1.txt (160B)792niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign793niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Signed narinfos id=1 count=1794niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Uploading 1 narinfos795niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete796niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (7.97ms)797niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (442.54µs)798niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (676.21µs)799niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Completed upload id=1800niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Upload complete. (116ms)801niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (10.37ms)802niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (10.32ms)803niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (485.63µs)804niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (602.21µs)805niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug806niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:807niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-63754-46525072/TestNARDeduplicationMetadataUploadBug3192052892/001/store/xi0k4gyg643vdvyx7brr2ch81nl28p60-file1.txt808niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst809niks3-go-unit-tests>         Compression: zstd810niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf811niks3-go-unit-tests>         NarSize: 160812niks3-go-unit-tests>         References: 813niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf814niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (957.83µs)815niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (898.46µs)816niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)817niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):818niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}819niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (8.04ms)820niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000821niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (4.29ms)822niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000823niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (4.14ms)824niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000825niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (2.03ms)826niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1.56ms)827niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (222.88µs)828niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2829niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1.98ms)830niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (426.67µs)831niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2832niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (358.71µs)833niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2834niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures835niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.27s)836niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted837niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures838niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received complete multipart upload request method=POST path=/api/multipart/complete839niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures840niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received complete multipart upload request method=POST path=/api/multipart/complete841niks3-go-unit-tests> {"timestamp":"2026-06-28T13:40:14.664144Z","level":"ERROR","fields":{"message":"complete_multipart_upload part error: \"part.1 not found\""},"target":"rustfs_ecstore::set_disk","filename":"crates/ecstore/src/set_disk.rs","line_number":3463,"threadName":"rustfs-worker","threadId":"ThreadId(4)"}842niks3-go-unit-tests> {"timestamp":"2026-06-28T13:40:14.664173Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket14, object=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst"},"target":"rustfs_ecstore::set_disk","filename":"crates/ecstore/src/set_disk.rs","line_number":3534,"threadName":"rustfs-worker","threadId":"ThreadId(4)"}843niks3-go-unit-tests> 2026/06/28 13:40:14 WARN CompleteMultipartUpload errored but object exists; treating as success error="One or more of the specified parts could not be found.  The part may not have been uploaded, or the specified entity tag may not match the part's entity tag." object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=MjYwM2FjYmItODgxMC00ZGQyLWI3ZTEtZWE1YzgzZTc1NWQ2LjczZDBlMTI5LTk1Y2ItNDNlOC1iMmEyLTIyMWUwNDNiNDA4YngxNzgyNjU0MDE0NjU1NDE0MDAw844niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=MjYwM2FjYmItODgxMC00ZGQyLWI3ZTEtZWE1YzgzZTc1NWQ2LjczZDBlMTI5LTk1Y2ItNDNlOC1iMmEyLTIyMWUwNDNiNDA4YngxNzgyNjU0MDE0NjU1NDE0MDAw parts=1845niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.29s)846niks3-go-unit-tests> === CONT  TestReadProxy404847niks3-go-unit-tests> 2026-06-28 13:40:14.678 UTC [63933] ERROR:  relation "goose_db_version" does not exist at character 36848niks3-go-unit-tests> 2026-06-28 13:40:14.678 UTC [63933] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC849niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug850niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /nix/var/nix/builds/nix-63754-46525072/TestNARDeduplicationMetadataUploadBug3192052892/001/store/mbhpilnh2vn2nqkzsdw3py33v72l5lq2-file2.txt851niks3-go-unit-tests> 2026-06-28 13:40:14.692 UTC [63935] ERROR:  relation "goose_db_version" does not exist at character 36852niks3-go-unit-tests> 2026-06-28 13:40:14.692 UTC [63935] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC853niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (14.61ms)854niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (632.63µs)855niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (1.13ms)856niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (4.36ms)857niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000858niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1.77ms)859niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (231.88µs)860niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2861niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (10.04ms)862niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (655.21µs)863niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (825.38µs)864niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.33s)865niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming866niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (3.63ms)867niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000868niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1.34ms)869niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (587.46µs)870niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2871niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.34s)872niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed873niks3-go-unit-tests> 2026-06-28 13:40:14.761 UTC [63944] ERROR:  relation "goose_db_version" does not exist at character 36874niks3-go-unit-tests> 2026-06-28 13:40:14.761 UTC [63944] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC875niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20241026095416_initial_model.sql (10.66ms)876niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received complete multipart upload request method=POST path=/api/multipart/complete877niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251210153512_drop_unused_gin_index.sql (642.08µs)878niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=MjYwM2FjYmItODgxMC00ZGQyLWI3ZTEtZWE1YzgzZTc1NWQ2LmUwODgzZTMzLWRjYTMtNDdmYS05ZmYxLTE0YjY1ZjYzOGFkYXgxNzgyNjU0MDE0NjU2MTEwMDAw parts=12879niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.41s)880niks3-go-unit-tests> === CONT  TestReadProxyNarinfo881niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20251218171726_add_pins.sql (4.85ms)882niks3-go-unit-tests> 2026/06/28 13:40:14 OK   20260628120000_add_object_size_and_stats.sql (2.47ms)883niks3-go-unit-tests> 2026/06/28 13:40:14 goose: successfully migrated database to version: 20260628120000884niks3-go-unit-tests> 2026/06/28 13:40:14 OK   1_commit_pending_closure.sql (1ms)885niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received uploads request method=POST path=/api/pending_closures886niks3-go-unit-tests> 2026/06/28 13:40:14 OK   2_object_stats_trigger.sql (439µs)887niks3-go-unit-tests> 2026/06/28 13:40:14 goose: up to current file version: 2888niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)889niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign890niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Signed narinfos id=2 count=1891niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Uploading 1 narinfos892niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.40s)893niks3-go-unit-tests> === CONT  TestIsValidCachePath894niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo895niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo896niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars897niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars898niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst899niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst900niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz901niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz902niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2903niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2904niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed905niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed906niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls907niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls908niks3-go-unit-tests> === RUN   TestIsValidCachePath/log909niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log910niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation911niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation912niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info913niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info914niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html915niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html916niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent917niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent918niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle919niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle920niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e921niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e922niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u923niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u924niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path925niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path926niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty927niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty928niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash929niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash930niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension931niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension932niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash933niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash934niks3-go-unit-tests> === CONT  TestParseSingleRange935niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete936niks3-go-unit-tests> === RUN   TestParseSingleRange/none937niks3-go-unit-tests> === PAUSE TestParseSingleRange/none938niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit939niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit940niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored941niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored942niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash943niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash944niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty945niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty946niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start947niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start948niks3-go-unit-tests> === RUN   TestParseSingleRange/closed949niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed950niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended951niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended952niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size953niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size954niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix955niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix956niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size957niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size958niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte959niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte960niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF961niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF962niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF963niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF964niks3-go-unit-tests> === CONT  TestObjectStatsTrigger965niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Completed upload id=2966niks3-go-unit-tests> 2026/06/28 13:40:14 INFO Upload complete. (79ms)967niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug968niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:969niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-63754-46525072/TestNARDeduplicationMetadataUploadBug3192052892/001/store/mbhpilnh2vn2nqkzsdw3py33v72l5lq2-file2.txt970niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst971niks3-go-unit-tests>         Compression: zstd972niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf973niks3-go-unit-tests>         NarSize: 160974niks3-go-unit-tests>         References: 975niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf976niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)977niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):978niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}979niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.78s)980niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest981niks3-go-unit-tests> 2026-06-28 13:40:15.052 UTC [63954] ERROR:  relation "goose_db_version" does not exist at character 36982niks3-go-unit-tests> 2026-06-28 13:40:15.052 UTC [63954] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC983niks3-go-unit-tests> 2026-06-28 13:40:15.076 UTC [63955] ERROR:  relation "goose_db_version" does not exist at character 36984niks3-go-unit-tests> 2026-06-28 13:40:15.076 UTC [63955] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC985niks3-go-unit-tests> 2026-06-28 13:40:15.092 UTC [63956] ERROR:  relation "goose_db_version" does not exist at character 36986niks3-go-unit-tests> 2026-06-28 13:40:15.092 UTC [63956] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC987niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (22.38ms)988niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (748.25µs)989niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (1.93ms)990niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (2.02ms)991niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 20260628120000992niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (13.75ms)993niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (338.25µs)994niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.04ms)995niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (180.17µs)996niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 2997niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (745.46µs)998niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.51s)999niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC1000niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (5.76ms)1001niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001002niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (11.18ms)1003niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (882.13µs)1004niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.73ms)1005niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (661.79µs)1006niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21007niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (2.49ms)1008niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.57s)1009niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file1010niks3-go-unit-tests> === CONT  TestMultipartCleanup1011niks3-go-unit-tests> 2026-06-28 13:40:15.127 UTC [63960] ERROR:  relation "goose_db_version" does not exist at character 361012niks3-go-unit-tests> 2026-06-28 13:40:15.127 UTC [63960] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1013niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (12.22ms)1014niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001015niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (889.63µs)1016niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (273.29µs)1017niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21018niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (7.51ms)1019niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (362.04µs)1020niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (681.58µs)1021niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (2.74ms)1022niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001023niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.29ms)1024niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (211.29µs)1025niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21026niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.49s)1027niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file1028niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.00s)1029niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)1030niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)1031niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)1032niks3-go-unit-tests> === CONT  TestClientCADerivations1033niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.52s)1034niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences1035niks3-go-unit-tests> 2026-06-28 13:40:15.169 UTC [63965] ERROR:  relation "goose_db_version" does not exist at character 361036niks3-go-unit-tests> 2026-06-28 13:40:15.169 UTC [63965] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1037niks3-go-unit-tests> 2026-06-28 13:40:15.169 UTC [63966] ERROR:  relation "goose_db_version" does not exist at character 361038niks3-go-unit-tests> 2026-06-28 13:40:15.169 UTC [63966] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1039niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (10.81ms)1040niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (10.05ms)1041niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (701.21µs)1042niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (511.83µs)1043niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.1ms)1044niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.54ms)1045niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (2.78ms)1046niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001047niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (3.22ms)1048niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001049niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.07ms)1050niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.11ms)1051niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (228.17µs)1052niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21053niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (340.21µs)1054niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21055niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.48s)1056niks3-go-unit-tests> === CONT  TestPinProtectsFromGC1057niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.49s)1058niks3-go-unit-tests> === CONT  TestClientWithDependencies1059niks3-go-unit-tests> 2026-06-28 13:40:15.251 UTC [63972] ERROR:  relation "goose_db_version" does not exist at character 361060niks3-go-unit-tests> 2026-06-28 13:40:15.251 UTC [63972] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1061niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (6.74ms)1062niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (416.42µs)1063niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (2.29ms)1064niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (41.75ms)1065niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001066niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.28ms)1067niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (216.08µs)1068niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21069niks3-go-unit-tests> 2026-06-28 13:40:15.320 UTC [63973] ERROR:  relation "goose_db_version" does not exist at character 361070niks3-go-unit-tests> 2026-06-28 13:40:15.320 UTC [63973] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1071niks3-go-unit-tests> 2026-06-28 13:40:15.320 UTC [63974] ERROR:  relation "goose_db_version" does not exist at character 361072niks3-go-unit-tests> 2026-06-28 13:40:15.320 UTC [63974] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1073niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.54s)1074niks3-go-unit-tests> === CONT  TestClientMultipleUploads1075niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (8.17ms)1076niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (8.33ms)1077niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (732.33µs)1078niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (685.38µs)1079niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (1.78ms)1080niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (3.68ms)1081niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (5.28ms)1082niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001083niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (3.64ms)1084niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001085niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.24ms)1086niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.13ms)1087niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (513.04µs)1088niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21089niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (443µs)1090niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21091niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.58s)1092niks3-go-unit-tests> === CONT  TestClientIntegration1093niks3-go-unit-tests> 2026-06-28 13:40:15.523 UTC [63980] ERROR:  relation "goose_db_version" does not exist at character 361094niks3-go-unit-tests> 2026-06-28 13:40:15.523 UTC [63980] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1095niks3-go-unit-tests> 2026-06-28 13:40:15.535 UTC [63981] ERROR:  relation "goose_db_version" does not exist at character 361096niks3-go-unit-tests> 2026-06-28 13:40:15.535 UTC [63981] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1097niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (14.01ms)1098niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (606.5µs)1099niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (1.06ms)1100niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (8.99ms)1101niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (643.67µs)1102niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (990.46µs)1103niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (3.45ms)1104niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001105niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (955.92µs)1106niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (258.38µs)1107niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21108niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (3.44ms)1109niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001110niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.79ms)1111niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (526.79µs)1112niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21113niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1114niks3-go-unit-tests> 2026-06-28 13:40:15.567 UTC [63982] ERROR:  relation "goose_db_version" does not exist at character 361115niks3-go-unit-tests> 2026-06-28 13:40:15.567 UTC [63982] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1116niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (12.15ms)1117niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (408.63µs)1118niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (819.83µs)1119niks3-go-unit-tests> 2026-06-28 13:40:15.592 UTC [63983] ERROR:  relation "goose_db_version" does not exist at character 361120niks3-go-unit-tests> 2026-06-28 13:40:15.592 UTC [63983] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1121niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (7.03ms)1122niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001123niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.23ms)1124niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (376.75µs)1125niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21126niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (4.75ms)1127niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (353.83µs)1128niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (681.46µs)1129niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (5.42ms)1130niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001131niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.08ms)1132niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (196.17µs)1133niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21134niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Created nix-cache-info in bucket bucket=bucket311135niks3-go-unit-tests> 2026-06-28 13:40:15.618 UTC [63985] ERROR:  relation "goose_db_version" does not exist at character 361136niks3-go-unit-tests> 2026-06-28 13:40:15.618 UTC [63985] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1137niks3-go-unit-tests> 2026-06-28 13:40:15.629 UTC [63986] ERROR:  relation "goose_db_version" does not exist at character 361138niks3-go-unit-tests> 2026-06-28 13:40:15.629 UTC [63986] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1139niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (5.8ms)1140niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (471.83µs)1141niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (1.12ms)1142niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received cleanup request method=DELETE path=/api/pending_closures1143niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Aborted multipart uploads count=11144niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (53.99ms)1145niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (49.29ms)1146niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001147niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.57s)1148niks3-go-unit-tests> === CONT  TestClientErrorHandling1149niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath1150niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath1151niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken1152niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken1153niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable1154niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable1155niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC1156niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (1.1ms)1157niks3-go-unit-tests> 2026/06/28 13:40:15 INFO OIDC provider initialized name=test1158niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (2.58ms)1159niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (2.65ms)1160niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (933.54µs)1161niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21162niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (1.51ms)1163niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001164niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.39ms)1165niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (209.54µs)1166niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21167niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Created nix-cache-info in bucket bucket=bucket321168niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Created nix-cache-info in bucket bucket=bucket331169niks3-go-unit-tests> 2026-06-28 13:40:15.728 UTC [63994] ERROR:  relation "goose_db_version" does not exist at character 361170niks3-go-unit-tests> 2026-06-28 13:40:15.728 UTC [63994] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1171niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (10.46ms)1172niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (558.38µs)1173niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (2.08ms)1174niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (4.91ms)1175niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001176niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (2.22ms)1177niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (507.92µs)1178niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21179niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Created nix-cache-info in bucket bucket=bucket341180niks3-go-unit-tests> 2026-06-28 13:40:15.795 UTC [64000] ERROR:  relation "goose_db_version" does not exist at character 361181niks3-go-unit-tests> 2026-06-28 13:40:15.795 UTC [64000] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1182niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1183niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1184niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1185niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1186niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1187niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1188niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1189niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.69s)1190niks3-go-unit-tests> === CONT  TestCacheStatsHandler1191niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.65s)1192niks3-go-unit-tests> === CONT  TestCacheConfigHandler1193niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer1194niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer1195niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured1196niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured1197niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys1198niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys1199niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1200niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1201niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects1202niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1203niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /nix/var/nix/builds/nix-63754-46525072/TestPinProtectsFromGC2355785603/001/store/h90hrig82qvj44r1x811x1dirakn22sk-pinned-file.txt1204niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /nix/var/nix/builds/nix-63754-46525072/TestPinProtectsFromGC2355785603/001/store/0yb4jdnkzxjl2s251xydskhvr7z9kh2k-unpinned-file.txt1205niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (20.98ms)1206niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (18.43ms)1207niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (2.29ms)1208niks3-go-unit-tests> === NAME  TestClientMultipleUploads1209niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /nix/var/nix/builds/nix-63754-46525072/TestClientMultipleUploads1618705400/001/store/f0mkackxh7lannz616m30w77xfdvgfzf-test-file-0.txt1210niks3-go-unit-tests> === NAME  TestClientCADerivations1211niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /nix/var/nix/builds/nix-63754-46525072/TestClientCADerivations3439197696/001/store/47rzzd550l809ix42s4sva122nwq8sv2-ca-test1212niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (2.56ms)1213niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001214niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.96ms)1215niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (383.83µs)1216niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21217niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Created nix-cache-info in bucket bucket=bucket351218niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1219niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1220niks3-go-unit-tests> === NAME  TestClientCADerivations1221niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1222niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1223niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1224niks3-go-unit-tests> === NAME  TestClientMultipleUploads1225niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /nix/var/nix/builds/nix-63754-46525072/TestClientMultipleUploads1618705400/001/store/6xrl3z7gid0b9baqfwy1bsq52s74cd0y-test-file-1.txt1226niks3-go-unit-tests> === NAME  TestClientIntegration1227niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /nix/var/nix/builds/nix-63754-46525072/TestClientIntegration3037289391/002/store/ga07ihj2cv5nx20ykrm05ia2d69gb68n-test-file.txt1228niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received uploads request method=POST path=/api/pending_closures1229niks3-go-unit-tests> 2026-06-28 13:40:15.942 UTC [64027] ERROR:  relation "goose_db_version" does not exist at character 361230niks3-go-unit-tests> 2026-06-28 13:40:15.942 UTC [64027] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1231niks3-go-unit-tests> === NAME  TestClientWithDependencies1232niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /nix/var/nix/builds/nix-63754-46525072/TestClientWithDependencies3252817265/001/store/bp927l2f4b7p8vr2qj8ybyil8bx443p0-test-script1233niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1234niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading h90hrig82qvj44r1x811x1dirakn22sk-pinned-file.txt (128B)1235niks3-go-unit-tests> === NAME  TestClientMultipleUploads1236niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /nix/var/nix/builds/nix-63754-46525072/TestClientMultipleUploads1618705400/001/store/ilzmpb6shiq3yslb15dpgq4l9ryk4imx-test-file-2.txt1237niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1238niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Signed narinfos id=1 count=11239niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Uploading 1 narinfos1240niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1241niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Completed upload id=11242niks3-go-unit-tests> 2026/06/28 13:40:15 INFO Upload complete. (103ms)1243niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20241026095416_initial_model.sql (11.28ms)1244niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251210153512_drop_unused_gin_index.sql (800.79µs)1245niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20251218171726_add_pins.sql (1.79ms)1246niks3-go-unit-tests> 2026/06/28 13:40:15 OK   20260628120000_add_object_size_and_stats.sql (2.82ms)1247niks3-go-unit-tests> 2026/06/28 13:40:15 goose: successfully migrated database to version: 202606281200001248niks3-go-unit-tests> 2026/06/28 13:40:15 OK   1_commit_pending_closure.sql (1.98ms)1249niks3-go-unit-tests> 2026/06/28 13:40:15 OK   2_object_stats_trigger.sql (520.71µs)1250niks3-go-unit-tests> 2026/06/28 13:40:15 goose: up to current file version: 21251niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1252niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1253niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1254niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1255niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1256niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1257niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1258niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1259niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware1260niks3-go-unit-tests> === NAME  TestClientWithDependencies1261niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1262niks3-go-unit-tests> 2026-06-28 13:40:15.996 UTC [64041] ERROR:  relation "goose_db_version" does not exist at character 361263niks3-go-unit-tests> 2026-06-28 13:40:15.996 UTC [64041] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1264niks3-go-unit-tests> 2026-06-28 13:40:16.003 UTC [64045] ERROR:  relation "goose_db_version" does not exist at character 361265niks3-go-unit-tests> 2026-06-28 13:40:16.003 UTC [64045] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1266niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20241026095416_initial_model.sql (7.03ms)1267niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20241026095416_initial_model.sql (7.53ms)1268niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251210153512_drop_unused_gin_index.sql (788.33µs)1269niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251210153512_drop_unused_gin_index.sql (779.46µs)1270niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251218171726_add_pins.sql (1.18ms)1271niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251218171726_add_pins.sql (1.2ms)1272niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20260628120000_add_object_size_and_stats.sql (3.16ms)1273niks3-go-unit-tests> 2026/06/28 13:40:16 goose: successfully migrated database to version: 202606281200001274niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20260628120000_add_object_size_and_stats.sql (4.58ms)1275niks3-go-unit-tests> 2026/06/28 13:40:16 goose: successfully migrated database to version: 202606281200001276niks3-go-unit-tests> 2026/06/28 13:40:16 OK   1_commit_pending_closure.sql (947.38µs)1277niks3-go-unit-tests> 2026/06/28 13:40:16 OK   1_commit_pending_closure.sql (2.44ms)1278niks3-go-unit-tests> 2026/06/28 13:40:16 OK   2_object_stats_trigger.sql (366.04µs)1279niks3-go-unit-tests> 2026/06/28 13:40:16 goose: up to current file version: 21280niks3-go-unit-tests> 2026/06/28 13:40:16 OK   2_object_stats_trigger.sql (512.46µs)1281niks3-go-unit-tests> 2026/06/28 13:40:16 goose: up to current file version: 21282niks3-go-unit-tests> 2026/06/28 13:40:16 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1283niks3-go-unit-tests> 2026/06/28 13:40:16 WARN mTLS auth: bound subjects configured but subject DN unavailable1284niks3-go-unit-tests> 2026/06/28 13:40:16 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1285niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.22s)1286niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader1287niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/api/pending_closures1288niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.23s)1289niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity1290niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1291niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 47rzzd550l809ix42s4sva122nwq8sv2-ca-test (144B)1292niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1293niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Signed narinfos id=1 count=11294niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 1 narinfos1295niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1296niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Completed upload id=11297niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Upload complete. (105ms)1298niks3-go-unit-tests> === NAME  TestClientCADerivations1299niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /nix/var/nix/builds/nix-63754-46525072/TestClientCADerivations3439197696/001/store/47rzzd550l809ix42s4sva122nwq8sv2-ca-test1300niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1301niks3-go-unit-tests>         Compression: zstd1302niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1303niks3-go-unit-tests>         NarSize: 1441304niks3-go-unit-tests>         References: 1305niks3-go-unit-tests>         Deriver: /nix/var/nix/builds/nix-63754-46525072/TestClientCADerivations3439197696/001/store/wn12f0c7020hyq8292acr0axhan5lrdf-ca-test.drv1306niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1307niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1308niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1309niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1310niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/api/pending_closures1311niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/api/pending_closures1312niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1313niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1314niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201315niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101316niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101317niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.28s)1318niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure1319niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/1320niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1321niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading ga07ihj2cv5nx20ykrm05ia2d69gb68n-test-file.txt (152B)1322niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1323niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Signed narinfos id=1 count=11324niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 1 narinfos1325niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1326niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1327niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading bp927l2f4b7p8vr2qj8ybyil8bx443p0-test-script (136B)1328niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/api/pending_closures1329niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/api/pending_closures1330niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1331niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 0yb4jdnkzxjl2s251xydskhvr7z9kh2k-unpinned-file.txt (128B)1332niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1333niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Signed narinfos id=1 count=11334niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 1 narinfos1335niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1336niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1337niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Signed narinfos id=2 count=11338niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 1 narinfos1339niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1340niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Completed upload id=21341niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Upload complete. (91ms)1342niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Completed upload id=11343niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Upload complete. (66ms)1344niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Completed upload id=11345niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Upload complete. (111ms)1346niks3-go-unit-tests> === NAME  TestClientCADerivations1347niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: error: binary cache 's3://bucket31?endpoint=http://localhost:52262&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/nix/var/nix/builds/nix-63754-46525072/TestClientCADerivations3439197696/001/store'1348niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11349niks3-go-unit-tests> === NAME  TestClientIntegration1350niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1351niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-63754-46525072/TestClientIntegration3037289391/002/store/ga07ihj2cv5nx20ykrm05ia2d69gb68n-test-file.txt1352niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1353niks3-go-unit-tests>         Compression: zstd1354niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11355niks3-go-unit-tests>         NarSize: 1521356niks3-go-unit-tests>         References: 1357niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11358niks3-go-unit-tests> === NAME  TestClientWithDependencies1359niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/nix/var/nix/builds/nix-63754-46525072/TestClientWithDependencies3252817265/001/store) requires matching store prefix1360niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/api/pending_closures1361niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/api/pending_closures1362niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.94s)1363niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts1364niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received request for more parts method=POST path=/1365niks3-go-unit-tests> === NAME  TestClientIntegration1366niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1367niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1368niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1369niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1370niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1371niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.89s)1372niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart1373niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading f0mkackxh7lannz616m30w77xfdvgfzf-test-file-0.txt (160B)1374niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 6xrl3z7gid0b9baqfwy1bsq52s74cd0y-test-file-1.txt (160B)1375niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading ilzmpb6shiq3yslb15dpgq4l9ryk4imx-test-file-2.txt (160B)1376niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received complete multipart upload request method=POST path=/1377niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1378niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Signed narinfos id=1 count=11379niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1380niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Signed narinfos id=2 count=11381niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1382niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Signed narinfos id=3 count=11383niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Uploading 3 narinfos1384niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1385niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Completed upload id=11386niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1387niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Completed upload id=21388niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1389niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Completed upload id=31390niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Upload complete. (112ms)1391niks3-go-unit-tests> === NAME  TestClientMultipleUploads1392niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 161.817083ms1393niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.79s)1394niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1395niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/1396niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1397niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received complete multipart upload request method=POST path=/1398niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1399niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received request for more parts method=POST path=/1400niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1401niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/1402niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)1403niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1404niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1405niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1406niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1407niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo1408niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo1409niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation1410niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals1411niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark1412niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name1413niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file1414niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output1415niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log1416niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type1417niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key1418niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing1419niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute1420niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain1421niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar1422niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz1423niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal1424niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type1425niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst1426niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type1427niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type1428niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html1429niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info1430niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.00s)1431niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)1432niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)1433niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1434niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1435niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1436niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1437niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1438niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)1439niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1440niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)1441niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)1442niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)1443niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1444niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1445niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1446niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)1447niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1448niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1449niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1450niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1451niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)1452niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1453niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar1454niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size1455niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar1456niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)1457niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1458niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1459niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1460niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)1461niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo1462niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html1463niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash1464niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension1465niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash1466niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty1467niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path1468niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u1469niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e1470niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle1471niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent1472niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed1473niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info1474niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation1475niks3-go-unit-tests> === CONT  TestIsValidCachePath/log1476niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls1477niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz1478niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz21479niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst1480niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars1481niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.00s)1482niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)1483niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)1484niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)1485niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1486niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)1487niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)1488niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)1489niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1490niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1491niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1492niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1493niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1494niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1495niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)1496niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)1497niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)1498niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)1499niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1500niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)1501niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)1502niks3-go-unit-tests> === CONT  TestParseSingleRange/none1503niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended1504niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF1505niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF1506niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte1507niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size1508niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix1509niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size1510niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty1511niks3-go-unit-tests> === CONT  TestParseSingleRange/closed1512niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start1513niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored1514niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash1515niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit1516niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.00s)1517niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)1518niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)1519niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1520niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1521niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)1522niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1523niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)1524niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1525niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1526niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)1527niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1528niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1529niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1530niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)1531niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath1532niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable1533niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken1534niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received create pin request method=POST path=/api/pins/myapp1535niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Created/updated pin name=myapp store_path=/nix/var/nix/builds/nix-63754-46525072/TestPinProtectsFromGC2355785603/001/store/h90hrig82qvj44r1x811x1dirakn22sk-pinned-file.txt narinfo_key=h90hrig82qvj44r1x811x1dirakn22sk.narinfo1536niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Starting cleanup of old closures method=DELETE path=/api/closures1537niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Garbage collection started1538niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Aborted multipart uploads count=01539niks3-go-unit-tests> 2026/06/28 13:40:16 WARN Force mode enabled - objects will be deleted immediately without grace period1540niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Starting cleanup of old closures method=DELETE path=/api/closures1541niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Garbage collection started1542niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Aborted multipart uploads count=01543niks3-go-unit-tests> 2026/06/28 13:40:16 WARN Force mode enabled - objects will be deleted immediately without grace period1544niks3-go-unit-tests> 2026-06-28 13:40:16.195 UTC [64075] ERROR:  relation "goose_db_version" does not exist at character 361545niks3-go-unit-tests> 2026-06-28 13:40:16.195 UTC [64075] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1546niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20241026095416_initial_model.sql (7.92ms)1547niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251210153512_drop_unused_gin_index.sql (4.17ms)1548niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251218171726_add_pins.sql (1.09ms)1549niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20260628120000_add_object_size_and_stats.sql (1.98ms)1550niks3-go-unit-tests> 2026/06/28 13:40:16 goose: successfully migrated database to version: 202606281200001551niks3-go-unit-tests> 2026/06/28 13:40:16 OK   1_commit_pending_closure.sql (1.4ms)1552niks3-go-unit-tests> 2026/06/28 13:40:16 OK   2_object_stats_trigger.sql (260.71µs)1553niks3-go-unit-tests> 2026/06/28 13:40:16 goose: up to current file version: 21554niks3-go-unit-tests> 2026/06/28 13:40:16 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1555niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.25s)1556niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer1557niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys1558niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1559niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured1560niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)1561niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)1562niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)1563niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)1564niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)1565niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1566niks3-go-unit-tests> 2026/06/28 13:40:16 INFO OIDC auth successful provider=test1567niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1568niks3-go-unit-tests> 2026/06/28 13:40:16 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]1569niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1570niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1571niks3-go-unit-tests> 2026/06/28 13:40:16 WARN Authentication failed token_preview=eyJhbGciOi...IrEvs5PE4w token_length=702 oidc_error="bound claims validation failed: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]1572niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.28s)1573niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1574niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1575niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1576niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1577niks3-go-unit-tests> 2026-06-28 13:40:16.255 UTC [64079] ERROR:  relation "goose_db_version" does not exist at character 361578niks3-go-unit-tests> 2026-06-28 13:40:16.255 UTC [64079] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1579niks3-go-unit-tests> 2026-06-28 13:40:16.269 UTC [64081] ERROR:  relation "goose_db_version" does not exist at character 361580niks3-go-unit-tests> 2026-06-28 13:40:16.269 UTC [64081] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1581niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20241026095416_initial_model.sql (8.36ms)1582niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251210153512_drop_unused_gin_index.sql (616.38µs)1583niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251218171726_add_pins.sql (896.67µs)1584niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20260628120000_add_object_size_and_stats.sql (1.85ms)1585niks3-go-unit-tests> 2026/06/28 13:40:16 goose: successfully migrated database to version: 202606281200001586niks3-go-unit-tests> 2026/06/28 13:40:16 OK   1_commit_pending_closure.sql (2ms)1587niks3-go-unit-tests> 2026/06/28 13:40:16 OK   2_object_stats_trigger.sql (528.67µs)1588niks3-go-unit-tests> 2026/06/28 13:40:16 goose: up to current file version: 21589niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20241026095416_initial_model.sql (5.78ms)1590niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251210153512_drop_unused_gin_index.sql (514.96µs)1591niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.26s)1592niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251218171726_add_pins.sql (1.7ms)1593niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20260628120000_add_object_size_and_stats.sql (1.89ms)1594niks3-go-unit-tests> 2026/06/28 13:40:16 goose: successfully migrated database to version: 202606281200001595niks3-go-unit-tests> 2026/06/28 13:40:16 OK   1_commit_pending_closure.sql (1.57ms)1596niks3-go-unit-tests> 2026/06/28 13:40:16 OK   2_object_stats_trigger.sql (390.33µs)1597niks3-go-unit-tests> 2026/06/28 13:40:16 goose: up to current file version: 21598niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/api/pending_closures1599niks3-go-unit-tests> 2026/06/28 13:40:16 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1600niks3-go-unit-tests> 2026-06-28 13:40:16.363 UTC [64086] ERROR:  relation "goose_db_version" does not exist at character 361601niks3-go-unit-tests> 2026-06-28 13:40:16.363 UTC [64086] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1602niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.03s)1603niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.02s)1604niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.02s)1605niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.30s)1606niks3-go-unit-tests> 2026-06-28 13:40:16.387 UTC [64087] ERROR:  relation "goose_db_version" does not exist at character 361607niks3-go-unit-tests> 2026-06-28 13:40:16.387 UTC [64087] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1608niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20241026095416_initial_model.sql (18.65ms)1609niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251210153512_drop_unused_gin_index.sql (591.63µs)1610niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251218171726_add_pins.sql (1.08ms)1611niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20260628120000_add_object_size_and_stats.sql (2.6ms)1612niks3-go-unit-tests> 2026/06/28 13:40:16 goose: successfully migrated database to version: 202606281200001613niks3-go-unit-tests> 2026/06/28 13:40:16 OK   1_commit_pending_closure.sql (2.15ms)1614niks3-go-unit-tests> 2026/06/28 13:40:16 OK   2_object_stats_trigger.sql (248.58µs)1615niks3-go-unit-tests> 2026/06/28 13:40:16 goose: up to current file version: 21616niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20241026095416_initial_model.sql (6.85ms)1617niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251210153512_drop_unused_gin_index.sql (504.33µs)1618niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20251218171726_add_pins.sql (1.73ms)1619niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1620niks3-go-unit-tests> 2026/06/28 13:40:16 OK   20260628120000_add_object_size_and_stats.sql (3.81ms)1621niks3-go-unit-tests> 2026/06/28 13:40:16 goose: successfully migrated database to version: 202606281200001622niks3-go-unit-tests> 2026/06/28 13:40:16 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=197.168273ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1623niks3-go-unit-tests> 2026/06/28 13:40:16 OK   1_commit_pending_closure.sql (1.05ms)1624niks3-go-unit-tests> 2026/06/28 13:40:16 OK   2_object_stats_trigger.sql (362.83µs)1625niks3-go-unit-tests> 2026/06/28 13:40:16 goose: up to current file version: 21626niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=MjYwM2FjYmItODgxMC00ZGQyLWI3ZTEtZWE1YzgzZTc1NWQ2LjdjZWY2YTcyLTFkZGEtNDVkNC05NjI2LTJjNzI5Y2YzOWMyOXgxNzgyNjU0MDE2MjkzOTU4MDAw parts=101627niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1628niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Completed upload id=11629niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/api/pending_closures1630niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Received uploads request method=POST path=/api/pending_closures1631niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1632niks3-go-unit-tests> 2026/06/28 13:40:16 WARN Found objects in DB but missing from S3, will re-upload count=11633niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.39s)1634niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=2001 objects-failed-to-delete=01635niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=2001 objects-failed-to-delete=01636niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Vacuumed table table=pending_closures1637niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Vacuumed table table=pending_closures1638niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Vacuumed table table=pending_objects1639niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Vacuumed table table=pending_objects1640niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Vacuumed table table=multipart_uploads1641niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Vacuumed table table=multipart_uploads1642niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Vacuumed table table=closures1643niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Vacuumed table table=closures1644niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Vacuumed table table=objects1645niks3-go-unit-tests> 2026/06/28 13:40:16 INFO Vacuumed table table=objects1646niks3-go-unit-tests> 2026/06/28 13:40:16 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1647niks3-go-unit-tests> 2026/06/28 13:40:16 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=364.939109ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1648niks3-go-unit-tests> 2026/06/28 13:40:16 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=862.244019ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1649niks3-go-unit-tests> 2026/06/28 13:40:17 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.614355686s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1650niks3-go-unit-tests> 2026/06/28 13:40:18 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01651niks3-go-unit-tests> 2026/06/28 13:40:18 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01652niks3-go-unit-tests> === NAME  TestClientIntegration1653niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1654niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1655niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1656niks3-go-unit-tests> === NAME  TestClientIntegration1657niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1658niks3-go-unit-tests> --- PASS: TestClientIntegration (2.78s)1659niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.95s)1660niks3-go-unit-tests> 2026/06/28 13:40:19 WARN Rate limiter enabled after throttle name=s3-test rate=51661niks3-go-unit-tests> 2026/06/28 13:40:19 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1662niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1663niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101664niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001665niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (4.83s)1666niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)1667niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.33s)1668niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.45s)1669niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.36s)1670niks3-go-unit-tests> PASS1671niks3-go-unit-tests> 2026-06-28 13:40:20.044 UTC [63790] LOG:  received smart shutdown request1672niks3-go-unit-tests> 2026-06-28 13:40:20.045 UTC [63790] LOG:  background worker "logical replication launcher" (PID 63797) exited with exit code 11673niks3-go-unit-tests> 2026-06-28 13:40:20.054 UTC [63792] LOG:  shutting down1674niks3-go-unit-tests> 2026-06-28 13:40:20.054 UTC [63792] LOG:  checkpoint starting: shutdown immediate1675niks3-go-unit-tests> 2026-06-28 13:40:21.098 UTC [63792] LOG:  checkpoint complete: wrote 13438 buffers (82.0%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.765 s, sync=0.239 s, total=1.044 s; sync files=14094, longest=0.001 s, average=0.001 s; distance=193031 kB, estimate=193031 kB; lsn=0/D194B30, redo lsn=0/D194B301676niks3-go-unit-tests> 2026-06-28 13:40:21.101 UTC [63790] LOG:  database system is shut down1677niks3-go-unit-tests> Running OIDC tests...1678niks3-go-unit-tests> === RUN   TestGlobMatch1679niks3-go-unit-tests> === PAUSE TestGlobMatch1680niks3-go-unit-tests> === RUN   TestAudienceForIssuer1681niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1682niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1683niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1684niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1685niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1686niks3-go-unit-tests> === RUN   TestValidateToken_Expired1687niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1688niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1689niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1690niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1691niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1692niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1693niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1694niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1695niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1696niks3-go-unit-tests> === CONT  TestGlobMatch1697niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1698niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1699niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1700niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1701niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1702niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1703niks3-go-unit-tests> === RUN   TestGlobMatch/*_1704niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1705niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1706niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1707niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1708niks3-go-unit-tests> === CONT  TestValidateToken_Expired1709niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1710niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1711niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1712niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1713niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1714niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1715niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1716niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1717niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1718niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1719niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1720niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1721niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1722niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1723niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1724niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1725niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1726niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1727niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1728niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1729niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1730niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1731niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1732niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1733niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01734niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01735niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1736niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1737niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1738niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1739niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1740niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1741niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1742niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1743niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1744niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1745niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1746niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1747niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1748niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1749niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1750niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1751niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1752niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1753niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1754niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1755niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1756niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1757niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1758niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1759niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1760niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01761niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1762niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1763niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1764niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1765niks3-go-unit-tests> === CONT  TestAudienceForIssuer1766niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1767niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1768niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1769niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1770niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1771niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1772niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1773niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1774niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1775niks3-go-unit-tests> === CONT  TestGlobMatch/*_1776niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1777niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1778niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1779niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1780niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1781niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1782niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1783niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1784niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1785niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1786niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1787niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1788niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1789niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1790niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1791niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1792niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1793niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1794niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1795niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1796niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1797niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1798niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1799niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1800niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1801niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1802niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1803niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1804niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1805niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1806niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1807niks3-go-unit-tests> 2026/06/28 13:40:21 INFO OIDC provider initialized name=test1808niks3-go-unit-tests> 2026/06/28 13:40:21 INFO OIDC provider initialized name=test1809niks3-go-unit-tests> 2026/06/28 13:40:21 INFO OIDC provider initialized name=provider11810niks3-go-unit-tests> 2026/06/28 13:40:21 INFO OIDC provider initialized name=provider11811niks3-go-unit-tests> 2026/06/28 13:40:21 INFO OIDC provider initialized name=test1812niks3-go-unit-tests> 2026/06/28 13:40:21 INFO OIDC provider initialized name=test1813niks3-go-unit-tests> 2026/06/28 13:40:21 INFO OIDC provider initialized name=test1814niks3-go-unit-tests> 2026/06/28 13:40:21 INFO OIDC provider initialized name=provider21815niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1816niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1817niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1818niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1819niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1820niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1821niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.01s)1822niks3-go-unit-tests> PASS1823niks3-go-unit-tests> Running hook tests...1824niks3-go-unit-tests> === RUN   TestSendPathsEmpty1825niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1826niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1827niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1828niks3-go-unit-tests> === RUN   TestQueueDeduplication1829niks3-go-unit-tests> === PAUSE TestQueueDeduplication1830niks3-go-unit-tests> === RUN   TestQueueRemove1831niks3-go-unit-tests> === PAUSE TestQueueRemove1832niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1833niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1834niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1835niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1836niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1837niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1838niks3-go-unit-tests> === RUN   TestServerClientIntegration1839niks3-go-unit-tests> === PAUSE TestServerClientIntegration1840niks3-go-unit-tests> === RUN   TestServerQueueError1841niks3-go-unit-tests> === PAUSE TestServerQueueError1842niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1843niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1844niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1845niks3-go-unit-tests>         PASS1846niks3-go-unit-tests>         1847niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.01s)1848niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1849niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1850niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1851niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1852niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1853niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1854niks3-go-unit-tests> === CONT  TestSendPathsEmpty1855niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1856niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1857niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1858niks3-go-unit-tests> === CONT  TestQueueRemove1859niks3-go-unit-tests> === CONT  TestQueueDeduplication1860niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1861niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1862niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1863niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1864niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1865niks3-go-unit-tests> === CONT  TestServerClientIntegration1866niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1867niks3-go-unit-tests> === CONT  TestServerQueueError1868niks3-go-unit-tests> 2026/06/28 13:40:22 ERROR Failed to queue paths error="permission denied" count=11869niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1870niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.01s)1871niks3-go-unit-tests> 2026/06/28 13:40:22 INFO Upload queue status pending=21872niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.01s)1873niks3-go-unit-tests> 2026/06/28 13:40:22 WARN Store path no longer exists (garbage collected?), removing from queue path=/nix/var/nix/builds/nix-63754-46525072/TestWorkerSkipsGCdPaths2063916073/002/nonexistent1874niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)1875niks3-go-unit-tests> 2026/06/28 13:40:22 INFO Upload queue status pending=21876niks3-go-unit-tests> 2026/06/28 13:40:22 INFO Uploading batch count=21877niks3-go-unit-tests> --- PASS: TestQueueRemove (0.01s)1878niks3-go-unit-tests> 2026/06/28 13:40:22 INFO Uploading batch count=11879niks3-go-unit-tests> 2026/06/28 13:40:22 INFO Upload queue status pending=21880niks3-go-unit-tests> 2026/06/28 13:40:22 INFO Uploading batch count=11881niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1882niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.06s)1883niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.06s)1884niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.06s)1885niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.17s)1886niks3-go-unit-tests> PASS18871888post-build step Upload coverage to codecov: ok1889Skipping codecov: project=Mic92/niks3 attr=aarch64-darwin.go-unit-tests