nixbot

1this derivation will be built:2  /nix/store/djgg6y2gwjv6lw4dy4jabyz22x800amr-niks3-go-unit-tests.drv3building '/nix/store/djgg6y2gwjv6lw4dy4jabyz22x800amr-niks3-go-unit-tests.drv' on 'ssh-ng://nix@jamie'4building '/nix/store/djgg6y2gwjv6lw4dy4jabyz22x800amr-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  TestFileTokenMissing76niks3-go-unit-tests> === CONT  TestRateLimiterFeedback77niks3-go-unit-tests> === CONT  TestConvertHashToNix3278niks3-go-unit-tests> === CONT  TestDumpPathWriterError79niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches80niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix3281niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility82niks3-go-unit-tests> === CONT  TestStaticToken83niks3-go-unit-tests> === CONT  TestSetClientTLSErrors84niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)85niks3-go-unit-tests> === CONT  TestShellSplitErrors86niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)87niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)88niks3-go-unit-tests> === CONT  TestShellSplit89niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)90niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)91niks3-go-unit-tests> === CONT  TestSetClientTLS92niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon93niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails94niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon95niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI96niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI97niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha51298niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand99niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)100niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall101niks3-go-unit-tests> === CONT  TestDumpPathSingleFile102niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash103niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)104niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh105niks3-go-unit-tests> === CONT  TestEncodeNixBase32106niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash107niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash108niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON109niks3-go-unit-tests> === CONT  TestFileTokenEmpty110niks3-go-unit-tests> === CONT  TestResolveStorePath111niks3-go-unit-tests> === CONT  TestGetStorePathHash112niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path113niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path114niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken115niks3-go-unit-tests> === CONT  TestParsePathInfoJSON116niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format117niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format118niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format119niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format120niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input121niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input122niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only123niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only124niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON125niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON126niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format127niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only128niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess129niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input130niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file131niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON132niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file133niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file134niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file135niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility136niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format137niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field138niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer139niks3-go-unit-tests> === CONT  TestPartSizeForNAR140niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths141niks3-go-unit-tests> === CONT  TestCaseHackSuffix142niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport143niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter144niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32145niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512146niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody147niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)148niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input149niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error150niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file151niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix152niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)153niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI154niks3-go-unit-tests> 2026/06/28 14:38:03 WARN Rate limiter enabled after throttle name=server-test rate=5155niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum156niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input157niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)158niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file159niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error160niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists161niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.00s)162niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists163niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field164niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.00s)165niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text166niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text167niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive168niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive169niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text170niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text171niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method172niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash173niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)174niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)175niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)176niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)177niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)178niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)179niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.01s)180niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.01s)181niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.00s)182niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512183niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input184niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.01s)185niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)186niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)187niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format188niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format189niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format190niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format191niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32192niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)193niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format194niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format195niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert196niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert197niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA198niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA199niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport200niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport201niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert202niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.01s)203niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)204niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)205niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)206niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA207niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter208niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths209niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon210niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter211niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths212niks3-go-unit-tests> 2026/06/28 14:38:03 WARN Rate limiter enabled after throttle name=server-test rate=5213niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter214niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum215niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error216niks3-go-unit-tests> 2026/06/28 14:38:03 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:44675217niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error218niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter219niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error220niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum221niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method222niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file223niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport224niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file225niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file226niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file227niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file228niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.01s)229niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing230niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.01s)231niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)232niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)233niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)234niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)235niks3-go-unit-tests> 2026/06/28 14:38:03 WARN Rate limiter backed off name=server-test rate=5236niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.01s)237niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.02s)238niks3-go-unit-tests> 2026/06/28 14:38:03 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:44675239niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing240niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter241niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field242niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter243niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method244niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter245niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter246niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter247niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter248niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text249niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)250niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive251niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter252niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text253niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum254niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file255niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum256niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum257niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths258niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts259niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths260niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths261niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts262niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths263niks3-go-unit-tests> 2026/06/28 14:38:03 WARN Rate limiter enabled after throttle name=server-test rate=5264niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB265niks3-go-unit-tests> 2026/06/28 14:38:03 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:45771266niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB267niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists268niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object269niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object270niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB271niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB272niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing273niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB274niks3-go-unit-tests> 2026/06/28 14:38:03 WARN Rate limiter backed off name=server-test rate=5275niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error276niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.01s)277niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)278niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)279niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)280niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)281niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)282niks3-go-unit-tests> 2026/06/28 14:38:03 WARN Rate limiter enabled after throttle name=server-test rate=5283niks3-go-unit-tests> 2026/06/28 14:38:03 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:42259284niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum285niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB286niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts287niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object288niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum289niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum290niks3-go-unit-tests> 2026/06/28 14:38:03 WARN Rate limiter backed off name=server-test rate=5291niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error292niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error293niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error294niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path295niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)296niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)297niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)298niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.02s)299niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)300niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)301niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)302niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)303niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.01s)304niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)305niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)306niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)307niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)308niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)309niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)310niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)311niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.02s)312niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)313niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)314niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)315niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)316niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.02s)317niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)318niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)319niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.01s)320niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.01s)321niks3-go-unit-tests> 2026/06/28 14:38:03 http: TLS handshake error from 127.0.0.1:33402: remote error: tls: bad certificate322niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)323niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)324niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)325niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.01s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)327niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.01s)328niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.01s)329niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.04s)330niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.06s)331niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.06s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.09s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.01s)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 "nixbld".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 /build/postgres2599046188/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 /build/postgres2599046188/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /build/postgres2599046188:5432 - no response364niks3-go-unit-tests> 2026-06-28 14:38:05.750 UTC [334] LOG:  starting PostgreSQL 17.10 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit365niks3-go-unit-tests> 2026-06-28 14:38:05.751 UTC [334] LOG:  listening on Unix socket "/build/postgres2599046188/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-28 14:38:05.755 UTC [338] LOG:  database system was shut down at 2026-06-28 14:38:05 UTC367niks3-go-unit-tests> 2026-06-28 14:38:05.758 UTC [334] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /build/postgres2599046188:5432 - accepting connections369niks3-go-unit-tests> {"timestamp":"2026-06-28T14:38:06.118601492Z","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(387)"}370niks3-go-unit-tests> 371niks3-go-unit-tests> thread 'rustfs-worker' (1052) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:372niks3-go-unit-tests> Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }373niks3-go-unit-tests> note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace374niks3-go-unit-tests> === RUN   TestService_AuthMiddleware375niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware376niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader377niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader378niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects379niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects380niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware381niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware382niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC383niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC384niks3-go-unit-tests> === RUN   TestCacheConfigHandler385niks3-go-unit-tests> === PAUSE TestCacheConfigHandler386niks3-go-unit-tests> === RUN   TestCacheStatsHandler387niks3-go-unit-tests> === PAUSE TestCacheStatsHandler388niks3-go-unit-tests> === RUN   TestClientCADerivations389niks3-go-unit-tests> === PAUSE TestClientCADerivations390niks3-go-unit-tests> === RUN   TestClientErrorHandling391niks3-go-unit-tests> === PAUSE TestClientErrorHandling392niks3-go-unit-tests> === RUN   TestClientIntegration393niks3-go-unit-tests> === PAUSE TestClientIntegration394niks3-go-unit-tests> === RUN   TestClientMultipleUploads395niks3-go-unit-tests> === PAUSE TestClientMultipleUploads396niks3-go-unit-tests> === RUN   TestClientWithDependencies397niks3-go-unit-tests> === PAUSE TestClientWithDependencies398niks3-go-unit-tests> === RUN   TestPinProtectsFromGC399niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC400niks3-go-unit-tests> === RUN   TestGCAdvisoryLockBlocksConcurrentRun401niks3-go-unit-tests> 2026-06-28 14:38:06.217 UTC [1132] ERROR:  relation "goose_db_version" does not exist at character 36402niks3-go-unit-tests> 2026-06-28 14:38:06.217 UTC [1132] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC403niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (6.43ms)404niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (1.33ms)405niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (2.72ms)406niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (2.98ms)407niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 20260628120000408niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (1.83ms)409niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (841.29µs)410niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 2411niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.10s)412niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences413niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences414niks3-go-unit-tests> === RUN   TestGCMetrics415niks3-go-unit-tests> === PAUSE TestGCMetrics416niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew417niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew418niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams419niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams420niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams421niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams422niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty423niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty424niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest425niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest426niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask427niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask428niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates429niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates430niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail431niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail432niks3-go-unit-tests> === RUN   TestGracefulShutdownDrainsInflight433niks3-go-unit-tests> === PAUSE TestGracefulShutdownDrainsInflight434niks3-go-unit-tests> === RUN   TestService_healthCheckHandler435niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler436niks3-go-unit-tests> === RUN   TestGenerateLandingPage437niks3-go-unit-tests> === PAUSE TestGenerateLandingPage438niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug439niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug440niks3-go-unit-tests> === RUN   TestMetricsInventory441niks3-go-unit-tests> === PAUSE TestMetricsInventory442niks3-go-unit-tests> === RUN   TestService_NativeMTLS443niks3-go-unit-tests> === PAUSE TestService_NativeMTLS444niks3-go-unit-tests> === RUN   TestServerTLSConfig445niks3-go-unit-tests> === PAUSE TestServerTLSConfig446niks3-go-unit-tests> === RUN   TestMultipartCleanup447niks3-go-unit-tests> === PAUSE TestMultipartCleanup448niks3-go-unit-tests> === RUN   TestObjectStatsTrigger449niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger450niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC451niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC452niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest453niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest454niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted455niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted456niks3-go-unit-tests> === RUN   TestParseSingleRange457niks3-go-unit-tests> === PAUSE TestParseSingleRange458niks3-go-unit-tests> === RUN   TestIsValidCachePath459niks3-go-unit-tests> === PAUSE TestIsValidCachePath460niks3-go-unit-tests> === RUN   TestReadProxyNarinfo461niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo462niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed463niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed464niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming465niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming466niks3-go-unit-tests> === RUN   TestReadProxy404467niks3-go-unit-tests> === PAUSE TestReadProxy404468niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath469niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath470niks3-go-unit-tests> === RUN   TestReadProxyHead471niks3-go-unit-tests> === PAUSE TestReadProxyHead472niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet473niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet474niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML475niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML476niks3-go-unit-tests> === RUN   TestReadProxyDisabled477niks3-go-unit-tests> === PAUSE TestReadProxyDisabled478niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest479niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest480niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload481niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload482niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists483niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists484niks3-go-unit-tests> === RUN   TestService_Rustfstest485niks3-go-unit-tests> === PAUSE TestService_Rustfstest486niks3-go-unit-tests> === RUN   TestSystemdListenerNotActivated487niks3-go-unit-tests> --- PASS: TestSystemdListenerNotActivated (0.00s)488niks3-go-unit-tests> === RUN   TestWatchdogBeatsWhenHealthy489niks3-go-unit-tests> --- PASS: TestWatchdogBeatsWhenHealthy (0.02s)490niks3-go-unit-tests> === RUN   TestWatchdogSkipsWhenUnhealthy491niks3-go-unit-tests> 2026/06/28 14:38:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"492niks3-go-unit-tests> 2026/06/28 14:38:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"493niks3-go-unit-tests> 2026/06/28 14:38:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"494niks3-go-unit-tests> 2026/06/28 14:38:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"495niks3-go-unit-tests> 2026/06/28 14:38:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"496niks3-go-unit-tests> 2026/06/28 14:38:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"497niks3-go-unit-tests> 2026/06/28 14:38:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"498niks3-go-unit-tests> 2026/06/28 14:38:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"499niks3-go-unit-tests> 2026/06/28 14:38:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"500niks3-go-unit-tests> 2026/06/28 14:38:06 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"501niks3-go-unit-tests> --- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)502niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle503niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle504niks3-go-unit-tests> === RUN   TestProxyWriteTimeout505niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout506niks3-go-unit-tests> === RUN   TestIsValidUploadKey507niks3-go-unit-tests> === PAUSE TestIsValidUploadKey508niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys509niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys510niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody511niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody512niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler513niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler514niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler515niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler516niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity517niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity518niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered519niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered520niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT521niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT522niks3-go-unit-tests> === CONT  TestService_AuthMiddleware523niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted524niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload525niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody526niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity527niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams528niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler529niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail530niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler531niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug532niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest533niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC534niks3-go-unit-tests> === CONT  TestObjectStatsTrigger535niks3-go-unit-tests> === CONT  TestMultipartCleanup536niks3-go-unit-tests> === CONT  TestServerTLSConfig537niks3-go-unit-tests> === CONT  TestService_NativeMTLS538niks3-go-unit-tests> === CONT  TestMetricsInventory539niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT540niks3-go-unit-tests> === CONT  TestProxyWriteTimeout541niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys542niks3-go-unit-tests> === CONT  TestIsValidUploadKey543niks3-go-unit-tests> === CONT  TestClientIntegration544niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams545niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew546niks3-go-unit-tests> === CONT  TestGCMetrics547niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences548niks3-go-unit-tests> === CONT  TestPinProtectsFromGC549niks3-go-unit-tests> === CONT  TestClientWithDependencies550niks3-go-unit-tests> === CONT  TestClientMultipleUploads551niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath552niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest553niks3-go-unit-tests> === CONT  TestReadProxyDisabled554niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML555niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet556niks3-go-unit-tests> === CONT  TestReadProxyHead557niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered558niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed559niks3-go-unit-tests> === CONT  TestReadProxy404560niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming561niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists562niks3-go-unit-tests> === CONT  TestCacheConfigHandler563niks3-go-unit-tests> === CONT  TestClientErrorHandling564niks3-go-unit-tests> === CONT  TestClientCADerivations565niks3-go-unit-tests> === CONT  TestCacheStatsHandler566niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle567niks3-go-unit-tests> === CONT  TestIsValidCachePath568niks3-go-unit-tests> === CONT  TestReadProxyNarinfo569niks3-go-unit-tests> === CONT  TestParseSingleRange570niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask571niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates572niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest573niks3-go-unit-tests> === CONT  TestService_healthCheckHandler574niks3-go-unit-tests> === CONT  TestGenerateLandingPage575niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight576niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware577niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo578niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo579niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer580niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars581niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer582niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars583niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC584niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty585niks3-go-unit-tests> === CONT  TestService_Rustfstest586niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)587niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA588niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo589niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo590niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info591niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath592niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath593niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst594niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken595niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info596niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA597niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects598niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file599niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file600niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file601niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file602niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA603niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal604niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal605niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file606niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken607niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable608niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable609niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Starting HTTP server address=127.0.0.1:39613610niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath611niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file612niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable613niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key614niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken615niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)616niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured617niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader618niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured619niks3-go-unit-tests> === RUN   TestParseSingleRange/none620niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys621niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys622niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key623niks3-go-unit-tests> === PAUSE TestParseSingleRange/none624niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator625niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo626niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)627niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key628niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)629niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key630niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst631niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key632niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar633niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz634niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo635niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit636niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar637niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit638niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored639niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar640niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar641niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size642niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)643niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored644niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size645niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info646niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator647niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer648niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator649niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz650niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2651niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2652niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed653niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed654niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls655niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls656niks3-go-unit-tests> === RUN   TestIsValidCachePath/log657niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log658niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation659niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation660niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info661niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info662niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys663niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html664niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html665niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent666niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent667niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured668niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle669niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle670niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key671niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e672niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e673niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u674niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)675niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u676niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)677niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)678niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo679niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received request for more parts method=POST path=/680niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.02s)681niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)682niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)683niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)684niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar685niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/686niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size687niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal688niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete multipart upload request method=POST path=/689niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar690niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.02s)691niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)692niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)693niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)694niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)695niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash696niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/697niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash698niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst699niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty700niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst701niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty702niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz703niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start704niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz705niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path706niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain707niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path708niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain709niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing710niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing711niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log712niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty713niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log714niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty715niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start716niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash717niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.02s)718niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)719niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)720niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)721niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)722niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file723niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash724niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)725niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)726niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)727niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)728niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)729niks3-go-unit-tests> === RUN   TestParseSingleRange/closed730niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension731niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed732niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension733niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file734niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash735niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash736niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo737niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name738niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent739niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name740niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path741niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty742niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u743niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html744niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst745niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz746niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars747niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended748niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz2749niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e750niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash751niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended752niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size753niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash754niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size755niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark756niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls757niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info758niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark759niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix760niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix761niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals762niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size763niks3-go-unit-tests> === CONT  TestIsValidCachePath/log764niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size765niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte766niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte767niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed768niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF769niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF770niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)771niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF772niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle773niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF774niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension775niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation776niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals777niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation778niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation779niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF780niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size781niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte782niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty783niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix784niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start785niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored786niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit787niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended788niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output789niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF790niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output791niks3-go-unit-tests> === CONT  TestParseSingleRange/closed792niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.00s)793niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)794niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)795niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)796niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)797niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)798niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)799niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)800niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)801niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)802niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)803niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)804niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)805niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)806niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)807niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)808niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)809niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)810niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)811niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)812niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)813niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info814niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size815niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash816niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info817niks3-go-unit-tests> === CONT  TestParseSingleRange/none818niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html819niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html820niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.00s)821niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)822niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)823niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)824niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)825niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)826niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)827niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)828niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)829niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)830niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)831niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)832niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)833niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)834niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)835niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type836niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type837niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type838niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type839niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type840niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type841niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal842niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal843niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar844niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar845niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute846niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute847niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key848niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key849niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type850niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type851niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo852niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type853niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute854niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation855niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log856niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type857niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals858niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar859niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing860niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal861niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type862niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key863niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name864niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info865niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output866niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file867niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain868niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz869niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst870niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html871niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type872niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark873niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.02s)874niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)875niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)876niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)877niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)878niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)879niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)880niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)881niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)882niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)883niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)884niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)885niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)886niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)887niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)888niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)889niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)890niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)891niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)892niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)893niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)894niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)895niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)896niks3-go-unit-tests> 2026/06/28 14:38:06 INFO OIDC provider initialized name=test897niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Shutdown signal received, draining in-flight requests timeout=10s898niks3-go-unit-tests> --- PASS: TestGracefulShutdownDrainsInflight (0.08s)899niks3-go-unit-tests> 2026-06-28 14:38:06.597 UTC [1260] ERROR:  relation "goose_db_version" does not exist at character 36900niks3-go-unit-tests> 2026-06-28 14:38:06.597 UTC [1260] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC901niks3-go-unit-tests> 2026-06-28 14:38:06.598 UTC [1259] ERROR:  relation "goose_db_version" does not exist at character 36902niks3-go-unit-tests> 2026-06-28 14:38:06.598 UTC [1259] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC903niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (20.82ms)904niks3-go-unit-tests> 2026/06/28 14:38:06 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures905niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (4.51ms)906niks3-go-unit-tests> 2026-06-28 14:38:06.640 UTC [1279] ERROR:  relation "goose_db_version" does not exist at character 36907niks3-go-unit-tests> 2026-06-28 14:38:06.640 UTC [1279] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC908niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (25.1ms)909niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (67.63ms)910niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure911niks3-go-unit-tests> 2026-06-28 14:38:06.703 UTC [1278] ERROR:  relation "goose_db_version" does not exist at character 36912niks3-go-unit-tests> 2026-06-28 14:38:06.703 UTC [1278] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC913niks3-go-unit-tests> 2026-06-28 14:38:06.703 UTC [1281] ERROR:  relation "goose_db_version" does not exist at character 36914niks3-go-unit-tests> 2026-06-28 14:38:06.703 UTC [1281] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC915niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure916niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart917niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart918niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts919niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts920niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure921niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts922niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart923niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/924niks3-go-unit-tests> 2026-06-28 14:38:06.703 UTC [1301] ERROR:  relation "goose_db_version" does not exist at character 36925niks3-go-unit-tests> 2026-06-28 14:38:06.703 UTC [1301] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC926niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received request for more parts method=POST path=/927niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete multipart upload request method=POST path=/928niks3-go-unit-tests> 2026-06-28 14:38:06.703 UTC [1300] ERROR:  relation "goose_db_version" does not exist at character 36929niks3-go-unit-tests> 2026-06-28 14:38:06.703 UTC [1300] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC930niks3-go-unit-tests> 2026-06-28 14:38:06.704 UTC [1299] ERROR:  relation "goose_db_version" does not exist at character 36931niks3-go-unit-tests> 2026-06-28 14:38:06.704 UTC [1299] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC932niks3-go-unit-tests> 2026-06-28 14:38:06.708 UTC [1328] ERROR:  relation "goose_db_version" does not exist at character 36933niks3-go-unit-tests> 2026-06-28 14:38:06.708 UTC [1328] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC934niks3-go-unit-tests> 2026-06-28 14:38:06.709 UTC [1336] ERROR:  relation "goose_db_version" does not exist at character 36935niks3-go-unit-tests> 2026-06-28 14:38:06.709 UTC [1336] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC936niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (13.5ms)937niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (23.5ms)938niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 20260628120000939niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (5.92ms)940niks3-go-unit-tests> 2026/06/28 14:38:06 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=189.080502ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures941niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (15.85ms)942niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (3.83ms)943niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 2944niks3-go-unit-tests> 2026/06/28 14:38:06 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"945niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.25s)946niks3-go-unit-tests> 2026-06-28 14:38:06.741 UTC [1338] ERROR:  relation "goose_db_version" does not exist at character 36947niks3-go-unit-tests> 2026-06-28 14:38:06.741 UTC [1338] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC948niks3-go-unit-tests> 2026-06-28 14:38:06.744 UTC [1339] ERROR:  relation "goose_db_version" does not exist at character 36949niks3-go-unit-tests> 2026-06-28 14:38:06.744 UTC [1339] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC950niks3-go-unit-tests> 2026-06-28 14:38:06.750 UTC [1340] ERROR:  relation "goose_db_version" does not exist at character 36951niks3-go-unit-tests> 2026-06-28 14:38:06.750 UTC [1340] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC952niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (37.62ms)953niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 20260628120000954niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (12.17ms)955niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (8.13ms)956niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 2957niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (86.86ms)958niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (91.65ms)959niks3-go-unit-tests> {"timestamp":"2026-06-28T14:38:06.822862975Z","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(705)"}960niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (108.11ms)961niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (6.34ms)962niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (5.35ms)963niks3-go-unit-tests> 2026-06-28 14:38:06.828 UTC [1347] ERROR:  relation "goose_db_version" does not exist at character 36964niks3-go-unit-tests> 2026-06-28 14:38:06.828 UTC [1347] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC965niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (5.85ms)966niks3-go-unit-tests> 2026-06-28 14:38:06.831 UTC [1348] ERROR:  relation "goose_db_version" does not exist at character 36967niks3-go-unit-tests> 2026-06-28 14:38:06.831 UTC [1348] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC968niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (97.15ms)969niks3-go-unit-tests> 2026-06-28 14:38:06.840 UTC [1349] ERROR:  relation "goose_db_version" does not exist at character 36970niks3-go-unit-tests> 2026-06-28 14:38:06.840 UTC [1349] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC971niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (102.77ms)972niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (107.23ms)973niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (104.78ms)974niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (6.23ms)975niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (25.6ms)976niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (27.5ms)977niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (27.22ms)978niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (116.88ms)979niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (16.29ms)980niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (16.36ms)981niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (14.51ms)982niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (6.51ms)983niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 20260628120000984niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (4.22ms)985niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (6.24ms)986niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (25.37ms)987niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (3.08ms)988niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 2989niks3-go-unit-tests> 2026-06-28 14:38:06.870 UTC [1351] ERROR:  relation "goose_db_version" does not exist at character 36990niks3-go-unit-tests> 2026-06-28 14:38:06.870 UTC [1351] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC991niks3-go-unit-tests> 2026-06-28 14:38:06.870 UTC [1350] ERROR:  relation "goose_db_version" does not exist at character 36992niks3-go-unit-tests> 2026-06-28 14:38:06.870 UTC [1350] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC993niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (76.22ms)994niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received cleanup request method=DELETE path=/api/pending_closures995niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (20.1ms)996niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 20260628120000997niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.39s)998niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (17.81ms)999niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (18.43ms)1000niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001001niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Aborted multipart uploads count=01002niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (19.72ms)1003niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (15.33ms)1004niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (19.85ms)1005niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (5.39ms)1006niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (5.01ms)1007niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1008niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (84.29ms)1009niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (5.68ms)1010niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (14.95ms)1011niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001012niks3-go-unit-tests> 2026-06-28 14:38:06.890 UTC [1352] ERROR:  relation "goose_db_version" does not exist at character 361013niks3-go-unit-tests> 2026-06-28 14:38:06.890 UTC [1352] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1014niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (12.04ms)1015niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21016niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (10.89ms)1017niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (13.1ms)1018niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21019niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (11.21ms)1020niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (78.88ms)1021niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (20.65ms)1022niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001023niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.41s)1024niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (4.53ms)1025niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (24.96ms)1026niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (24.98ms)1027niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001028niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (5.91ms)1029niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (25.03ms)1030niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001031niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001032niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Created nix-cache-info in bucket bucket=bucket61033niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (8.29ms)1034niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21035niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received cleanup request method=DELETE path=/api/pending_closures1036niks3-go-unit-tests> 2026-06-28 14:38:06.905 UTC [1355] ERROR:  relation "goose_db_version" does not exist at character 361037niks3-go-unit-tests> 2026-06-28 14:38:06.905 UTC [1355] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1038niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Aborted multipart uploads count=11039niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (4.67ms)1040niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21041niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1042niks3-go-unit-tests> 2026-06-28 14:38:06.907 UTC [1356] ERROR:  relation "goose_db_version" does not exist at character 361043niks3-go-unit-tests> 2026-06-28 14:38:06.907 UTC [1356] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1044niks3-go-unit-tests> 2026-06-28 14:38:06.907 UTC [1357] ERROR:  relation "goose_db_version" does not exist at character 361045niks3-go-unit-tests> 2026-06-28 14:38:06.907 UTC [1357] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1046niks3-go-unit-tests> 2026-06-28 14:38:06.908 UTC [1358] ERROR:  relation "goose_db_version" does not exist at character 361047niks3-go-unit-tests> 2026-06-28 14:38:06.908 UTC [1358] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1048niks3-go-unit-tests> 2026-06-28 14:38:06.909 UTC [1359] ERROR:  relation "goose_db_version" does not exist at character 361049niks3-go-unit-tests> 2026-06-28 14:38:06.909 UTC [1359] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1050niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (6.92ms)1051niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (6.81ms)1052niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (31.89ms)1053niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (6.85ms)1054niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1055niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1056niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1057niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (3.37ms)1058niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21059niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (49.98ms)1060niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (3.34ms)1061niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21062niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1063niks3-go-unit-tests> 2026-06-28 14:38:06.913 UTC [1336] ERROR:  Closure does not exist: id=11064niks3-go-unit-tests> 2026-06-28 14:38:06.913 UTC [1336] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1065niks3-go-unit-tests> 2026-06-28 14:38:06.913 UTC [1336] STATEMENT:  -- name: CommitPendingClosure :exec1066niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1067niks3-go-unit-tests> 	1068niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.42s)1069niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (4.89ms)1070niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21071niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (21.82ms)1072niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (70.48ms)1073niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1074niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (17.64ms)1075niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (4.54ms)1076niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Created nix-cache-info in bucket bucket=bucket111077niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (4.71ms)1078niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (12.1ms)1079niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001080niks3-go-unit-tests> 2026-06-28 14:38:06.923 UTC [1362] ERROR:  relation "goose_db_version" does not exist at character 361081niks3-go-unit-tests> 2026-06-28 14:38:06.923 UTC [1362] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1082niks3-go-unit-tests> 2026/06/28 14:38:06 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=437.402504ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1083niks3-go-unit-tests> 2026-06-28 14:38:06.929 UTC [1364] ERROR:  relation "goose_db_version" does not exist at character 361084niks3-go-unit-tests> 2026-06-28 14:38:06.929 UTC [1364] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1085niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (14.97ms)1086niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (13.41ms)1087niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (18.31ms)1088niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001089niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (11.19ms)1090niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (15.33ms)1091niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001092niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (74.37ms)1093niks3-go-unit-tests> 2026-06-28 14:38:06.934 UTC [1366] ERROR:  relation "goose_db_version" does not exist at character 361094niks3-go-unit-tests> 2026-06-28 14:38:06.934 UTC [1366] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1095niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (3.56ms)1096niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21097niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (5.52ms)1098niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (5.15ms)1099niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (5.61ms)1100niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1101niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1102niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug4191847718/001/store/9rbgjv8g7603cn3d9yrp1bs4y9n7hyc3-file1.txt1103niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Created nix-cache-info in bucket bucket=bucket121104niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (3.84ms)1105niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21106niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (5.22ms)1107niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21108niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (50.4ms)1109niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (13.71ms)1110niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001111niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1112niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (14.62ms)1113niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001114niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (10.43ms)1115niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (36.71ms)1116niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (46.81ms)1117niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (3.8ms)1118niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (5.68ms)1119niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (5.33ms)1120niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (34.62ms)1121niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (6.96ms)1122niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (5.34ms)1123niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (3.08ms)1124niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21125niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (21.36ms)1126niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (16.06ms)1127niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (26.12ms)1128niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (6.34ms)1129niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (23.13ms)1130niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (27.11ms)1131niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (8.21ms)1132niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001133niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (3.23ms)1134niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21135niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (3.25ms)1136niks3-go-unit-tests> 2026/06/28 14:38:06 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1137niks3-go-unit-tests> 2026/06/28 14:38:06 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1138niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.47s)1139niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (3.34ms)1140niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (2.78ms)1141niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (3.12ms)1142niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.47s)1143niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (2.62ms)1144niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1145niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (3.22ms)1146niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (5.97ms)1147niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (3.19ms)1148niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (6.84ms)1149niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (3.46ms)1150niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (4.56ms)1151niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001152niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (2.5ms)1153niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21154niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (4.2ms)1155niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (14.09ms)1156niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (3.79ms)1157niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (3.95ms)1158niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (3.99ms)1159niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20241026095416_initial_model.sql (12.7ms)1160niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (4.24ms)1161niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (2.88ms)1162niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (4.24ms)1163niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001164niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (1.84ms)1165niks3-go-unit-tests> 2026-06-28 14:38:06.965 UTC [1402] ERROR:  relation "goose_db_version" does not exist at character 361166niks3-go-unit-tests> 2026-06-28 14:38:06.965 UTC [1402] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1167niks3-go-unit-tests> 2026-06-28 14:38:06.965 UTC [1403] ERROR:  relation "goose_db_version" does not exist at character 361168niks3-go-unit-tests> 2026-06-28 14:38:06.965 UTC [1403] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1169niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (4.5ms)1170niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001171niks3-go-unit-tests> 2026-06-28 14:38:06.967 UTC [1405] ERROR:  relation "goose_db_version" does not exist at character 361172niks3-go-unit-tests> 2026-06-28 14:38:06.967 UTC [1405] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1173niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (6.21ms)1174niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001175niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.48s)1176niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251210153512_drop_unused_gin_index.sql (3.99ms)1177niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (3.35ms)1178niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21179niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (3.14ms)1180niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (3.92ms)1181niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001182niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (4.88ms)1183niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001184niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (4.38ms)1185niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001186niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (4.45ms)1187niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001188niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (2.32ms)1189niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (3.37ms)1190niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (4.56ms)1191niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001192niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (1.02ms)1193niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21194niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (1.27ms)1195niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21196niks3-go-unit-tests> 2026-06-28 14:38:06.970 UTC [1407] ERROR:  relation "goose_db_version" does not exist at character 361197niks3-go-unit-tests> 2026-06-28 14:38:06.970 UTC [1407] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1198niks3-go-unit-tests> 2026-06-28 14:38:06.970 UTC [1409] ERROR:  relation "goose_db_version" does not exist at character 361199niks3-go-unit-tests> 2026-06-28 14:38:06.970 UTC [1409] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1200niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (3.01ms)1201niks3-go-unit-tests> 2026-06-28 14:38:06.971 UTC [1419] ERROR:  relation "goose_db_version" does not exist at character 361202niks3-go-unit-tests> 2026-06-28 14:38:06.971 UTC [1419] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1203niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (3.19ms)1204niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (2.97ms)1205niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20251218171726_add_pins.sql (3.59ms)1206niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (3.17ms)1207niks3-go-unit-tests> 2026-06-28 14:38:06.971 UTC [1428] ERROR:  relation "goose_db_version" does not exist at character 361208niks3-go-unit-tests> 2026-06-28 14:38:06.971 UTC [1428] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1209niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (3.41ms)1210niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (3.46ms)1211niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Created nix-cache-info in bucket bucket=bucket181212niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (3.51ms)1213niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001214niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (1.23ms)1215niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21216niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (1.16ms)1217niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (1.19ms)1218niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (1.22ms)1219niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21220niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (1.15ms)1221niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (1.24ms)1222niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21223niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21224niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21225niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21226niks3-go-unit-tests> 2026-06-28 14:38:06.973 UTC [1429] ERROR:  relation "goose_db_version" does not exist at character 361227niks3-go-unit-tests> 2026-06-28 14:38:06.973 UTC [1429] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1228niks3-go-unit-tests> 2026-06-28 14:38:06.973 UTC [1433] ERROR:  relation "goose_db_version" does not exist at character 361229niks3-go-unit-tests> 2026-06-28 14:38:06.973 UTC [1433] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1230niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (2.03ms)1231niks3-go-unit-tests> 2026-06-28 14:38:06.974 UTC [1425] ERROR:  relation "goose_db_version" does not exist at character 361232niks3-go-unit-tests> 2026-06-28 14:38:06.974 UTC [1425] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1233niks3-go-unit-tests> 2026/06/28 14:38:06 OK   20260628120000_add_object_size_and_stats.sql (3.5ms)1234niks3-go-unit-tests> 2026/06/28 14:38:06 goose: successfully migrated database to version: 202606281200001235niks3-go-unit-tests> 2026-06-28 14:38:06.975 UTC [1446] ERROR:  relation "goose_db_version" does not exist at character 361236niks3-go-unit-tests> 2026-06-28 14:38:06.975 UTC [1446] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1237niks3-go-unit-tests> 2026/06/28 14:38:06 INFO Received uploads request method=POST path=/api/pending_closures1238niks3-go-unit-tests> 2026-06-28 14:38:06.976 UTC [1447] ERROR:  relation "goose_db_version" does not exist at character 361239niks3-go-unit-tests> 2026-06-28 14:38:06.976 UTC [1447] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1240niks3-go-unit-tests> 2026/06/28 14:38:06 OK   2_object_stats_trigger.sql (2.52ms)1241niks3-go-unit-tests> 2026/06/28 14:38:06 goose: up to current file version: 21242niks3-go-unit-tests> 2026-06-28 14:38:06.976 UTC [1449] ERROR:  relation "goose_db_version" does not exist at character 361243niks3-go-unit-tests> 2026-06-28 14:38:06.976 UTC [1449] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1244niks3-go-unit-tests> 2026-06-28 14:38:06.978 UTC [1450] ERROR:  relation "goose_db_version" does not exist at character 361245niks3-go-unit-tests> 2026-06-28 14:38:06.978 UTC [1450] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1246niks3-go-unit-tests> 2026-06-28 14:38:06.978 UTC [1452] ERROR:  relation "goose_db_version" does not exist at character 361247niks3-go-unit-tests> 2026-06-28 14:38:06.978 UTC [1452] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1248niks3-go-unit-tests> 2026/06/28 14:38:06 OK   1_commit_pending_closure.sql (4.03ms)1249niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.51s)1250niks3-go-unit-tests> === NAME  TestClientWithDependencies1251niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies2291622309/001/store/vimjidrdx50h7jfdz6h4ah29smcz7ssp-test-script1252niks3-go-unit-tests> === NAME  TestClientIntegration1253niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /build/TestClientIntegration2266731096/002/store/y8cvhwgv824dlglpmq56hx3143czr886-test-file.txt1254niks3-go-unit-tests> 2026-06-28 14:38:07.015 UTC [1453] ERROR:  relation "goose_db_version" does not exist at character 361255niks3-go-unit-tests> 2026-06-28 14:38:07.015 UTC [1453] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1256niks3-go-unit-tests> === NAME  TestClientMultipleUploads1257niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads4066583152/001/store/7g9xlap49m4cb038immrk7pls1gnj9b5-test-file-0.txt1258niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (41.08ms)1259niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Aborted multipart uploads count=01260niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (36.91ms)1261niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (41.73ms)1262niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (42.85ms)1263niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (38.51ms)1264niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.51s)1265niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21266niks3-go-unit-tests> 2026/06/28 14:38:07 WARN Force mode enabled - objects will be deleted immediately without grace period1267niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (3.14ms)1268niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (8.4ms)1269niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (11.33ms)1270niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (13.53ms)1271niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (13.52ms)1272niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (13.61ms)1273niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (12.41ms)1274niks3-go-unit-tests> === NAME  TestClientMultipleUploads1275niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads4066583152/001/store/1fsmhgnqnmj426xbd7h7xh21m6sl8pad-test-file-1.txt1276niks3-go-unit-tests> === NAME  TestClientWithDependencies1277niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1278niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received cleanup request method=DELETE path=/api/pending_closures1279niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1280niks3-go-unit-tests> === NAME  TestClientMultipleUploads1281niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads4066583152/001/store/2adlv1g33fwigg2gx9dgv3a773v9d63m-test-file-2.txt1282niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Aborted multipart uploads count=11283niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (2.85ms)1284niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (3.36ms)1285niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (3.55ms)1286niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (3.77ms)1287niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (4.03ms)1288niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (4.22ms)1289niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (6.77ms)1290niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (5.86ms)1291niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (9.18ms)1292niks3-go-unit-tests> 2026/06/28 14:38:07 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=01293niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1294niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (6.57ms)1295niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (6.99ms)1296niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (6.95ms)1297niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (7.29ms)1298niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001299niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (7.49ms)1300niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.62s)1301niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (13.12ms)1302niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=pending_closures1303niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 9rbgjv8g7603cn3d9yrp1bs4y9n7hyc3-file1.txt (160B)1304niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.62s)1305niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=pending_objects1306niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=multipart_uploads1307niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (5.26ms)1308niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001309niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=closures1310niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.61s)1311niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=objects1312niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (6.47ms)1313niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001314niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (15.08ms)1315niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (15.38ms)1316niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (8.78ms)1317niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001318niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (15.61ms)1319niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (15.55ms)1320niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (3.08ms)1321niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (5.46ms)1322niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001323niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (4.61ms)1324niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001325niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (15.9ms)1326niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (3.25ms)1327niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (12.93ms)1328niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (2.56ms)1329niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21330niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (3.08ms)1331niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (2.97ms)1332niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (4.07ms)1333niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (5.88ms)1334niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001335niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (2.98ms)1336niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (2.57ms)1337niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (5.77ms)1338niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001339niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (2.48ms)1340niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (3.06ms)1341niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (2.98ms)1342niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (2.75ms)1343niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (3.27ms)1344niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (793.92µs)1345niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21346niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (1.09ms)1347niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21348niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (2.13ms)1349niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (1.47ms)1350niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21351niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (1.63ms)1352niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21353niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (4.09ms)1354niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1355niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1356niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (1.96ms)1357niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (1.92ms)1358niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21359niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (2.12ms)1360niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Signed narinfos id=1 count=11361niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 narinfos1362niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (2.95ms)1363niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1364niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.61s)1365niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (3.75ms)1366niks3-go-unit-tests> --- PASS: TestGCMetrics (0.63s)1367niks3-go-unit-tests> 2026/06/28 14:38:07 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1368niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.61s)1369niks3-go-unit-tests> {"timestamp":"2026-06-28T14:38:07.120029108Z","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(387)"}1370niks3-go-unit-tests> {"timestamp":"2026-06-28T14:38:07.120079338Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket25, 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(387)"}1371niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1372niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (2.64ms)1373niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21374niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (3.49ms)1375niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (4.85ms)1376niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (2.66ms)1377niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21378niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (3.29ms)1379niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001380niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (4.86ms)1381niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (5.08ms)1382niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20241026095416_initial_model.sql (14.6ms)1383niks3-go-unit-tests> 2026/06/28 14:38:07 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=YWZiNDk5OTktOTc4OC00MGM4LTg1ZmQtYTQzYTliZWEyZGEyLjEyYWVhNWFlLTVjZjItNDIwZi05MmM3LTZlMGY5YjJhOTZkYngxNzgyNjU3NDg3MDE4NjY5NDU41384niks3-go-unit-tests> 2026/06/28 14:38:07 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1385niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.62s)1386niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (2.44ms)1387niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=YWZiNDk5OTktOTc4OC00MGM4LTg1ZmQtYTQzYTliZWEyZGEyLjEyYWVhNWFlLTVjZjItNDIwZi05MmM3LTZlMGY5YjJhOTZkYngxNzgyNjU3NDg3MDE4NjY5NDU4 parts=11388niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (4.52ms)1389niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001390niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.62s)1391niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1392niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (3.05ms)1393niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (3.15ms)1394niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001395niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (2.86ms)1396niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251210153512_drop_unused_gin_index.sql (2.61ms)1397niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001398niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001399niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (979.46µs)1400niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21401niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (4.79ms)1402niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001403niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (4.29ms)1404niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001405niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Created nix-cache-info in bucket bucket=bucket361406niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.62s)1407niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.62s)1408niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (4.33ms)1409niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (5.35ms)1410niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed upload id=11411niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (4.78ms)1412niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (6.54ms)1413niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (1.21ms)1414niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21415niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (6.89ms)1416niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (2.2ms)1417niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21418niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20251218171726_add_pins.sql (8.71ms)1419niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (1.13ms)1420niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21421niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (9.09ms)1422niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Upload complete. (162ms)1423niks3-go-unit-tests> 2026/06/28 14:38:07 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1424niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1425niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (3.6ms)1426niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21427niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1428niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (6.55ms)1429niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21430niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (1.59ms)1431niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21432niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1433niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1434niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1435niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1436niks3-go-unit-tests> 2026/06/28 14:38:07 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1437niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1438niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1439niks3-go-unit-tests> 2026/06/28 14:38:07 WARN mTLS auth: bound subjects configured but subject DN unavailable1440niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1441niks3-go-unit-tests> 2026/06/28 14:38:07 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1442niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1443niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.63s)1444niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1445niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1446niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1447niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.63s)1448niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1449niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1450niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:1451niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug4191847718/001/store/9rbgjv8g7603cn3d9yrp1bs4y9n7hyc3-file1.txt1452niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1453niks3-go-unit-tests>         Compression: zstd1454niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1455niks3-go-unit-tests>         NarSize: 1601456niks3-go-unit-tests> 2026/06/28 14:38:07 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]1457niks3-go-unit-tests>         References: 1458niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1459niks3-go-unit-tests> 2026/06/28 14:38:07 OK   20260628120000_add_object_size_and_stats.sql (5.93ms)1460niks3-go-unit-tests> 2026/06/28 14:38:07 goose: successfully migrated database to version: 202606281200001461niks3-go-unit-tests> --- PASS: TestCacheStatsHandler (0.63s)1462niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.65s)1463niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.64s)1464niks3-go-unit-tests> 2026/06/28 14:38:07 WARN Authentication failed token_preview=eyJhbGciOi...Q_X17VN0pw 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]1465niks3-go-unit-tests> 2026/06/28 14:38:07 INFO OIDC auth successful provider=test1466niks3-go-unit-tests> 2026/06/28 14:38:07 OK   1_commit_pending_closure.sql (2.48ms)1467niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1468niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1469niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1470niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1471niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.63s)1472niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1473niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1474niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1475niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1476niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Created nix-cache-info in bucket bucket=bucket431477niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1478niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading vimjidrdx50h7jfdz6h4ah29smcz7ssp-test-script (136B)1479niks3-go-unit-tests> 2026/06/28 14:38:07 OK   2_object_stats_trigger.sql (1.12ms)1480niks3-go-unit-tests> 2026/06/28 14:38:07 goose: up to current file version: 21481niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1482niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading y8cvhwgv824dlglpmq56hx3143czr886-test-file.txt (152B)1483niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.64s)1484niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.64s)1485niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1486niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Signed narinfos id=1 count=11487niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 narinfos1488niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1489niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Signed narinfos id=1 count=11490niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 narinfos1491niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1492niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1493niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed upload id=11494niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Upload complete. (56ms)1495niks3-go-unit-tests> === NAME  TestClientWithDependencies1496niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies2291622309/001/store) requires matching store prefix1497niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed upload id=11498niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Upload complete. (111ms)1499niks3-go-unit-tests> === NAME  TestClientIntegration1500niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1501niks3-go-unit-tests>         StorePath: /build/TestClientIntegration2266731096/002/store/y8cvhwgv824dlglpmq56hx3143czr886-test-file.txt1502niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1503niks3-go-unit-tests>         Compression: zstd1504niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11505niks3-go-unit-tests>         NarSize: 1521506niks3-go-unit-tests>         References: 1507niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11508niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.67s)1509niks3-go-unit-tests> === NAME  TestClientIntegration1510niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1511niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1512niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1513niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1514niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1515niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug4191847718/001/store/4x4nzzpb3gshv3g60f7cvv4gsknd70kc-file2.txt1516niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.67s)1517niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1518niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1519niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC3930848558/001/store/wy77wp1z385fsmr5gnjdzkln9mz1nv7k-pinned-file.txt1520niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC3930848558/001/store/37wks4bhhznpbpkbg2chn2z23svb6asw-unpinned-file.txt1521niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Starting cleanup of old closures method=DELETE path=/api/closures1522niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Garbage collection started1523niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Aborted multipart uploads count=01524niks3-go-unit-tests> 2026/06/28 14:38:07 WARN Force mode enabled - objects will be deleted immediately without grace period1525niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC1526niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:1527niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A1528niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B1529niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)1530niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)1531niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects1532niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.71s)1533niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1534niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1535niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1536niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1537niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 7g9xlap49m4cb038immrk7pls1gnj9b5-test-file-0.txt (160B)1538niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 2adlv1g33fwigg2gx9dgv3a773v9d63m-test-file-2.txt (160B)1539niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1fsmhgnqnmj426xbd7h7xh21m6sl8pad-test-file-1.txt (160B)1540niks3-go-unit-tests> === NAME  TestClientCADerivations1541niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations2463061933/001/store/gpvbiw5qblp0yfxagk8qcswam6mi3wwh-ca-test1542niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1543niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1544niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Signed narinfos id=2 count=11545niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1546niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Signed narinfos id=3 count=11547niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1548niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Signed narinfos id=1 count=11549niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 3 narinfos1550niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1551niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=YWZiNDk5OTktOTc4OC00MGM4LTg1ZmQtYTQzYTliZWEyZGEyLmQ3NGEwMTBjLWVjYjQtNGU2OS04ODVlLTZkYWY3N2RkZTU1OHgxNzgyNjU3NDg2OTE2NTYzNDQ5 parts=101552niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1553niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1554niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed upload id=21555niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1556niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed upload id=11557niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1558niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed upload id=31559niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1560niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1561niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed upload id=11562niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Upload complete. (98ms)1563niks3-go-unit-tests> === NAME  TestClientMultipleUploads1564niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 130.192099ms1565niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1566niks3-go-unit-tests> 2026/06/28 14:38:07 WARN Found objects in DB but missing from S3, will re-upload count=11567niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=YWZiNDk5OTktOTc4OC00MGM4LTg1ZmQtYTQzYTliZWEyZGEyLmFkOWRhNDU3LWFiMmQtNDZmYi1iNDhiLTNjMDQ4YzQzZGMzZHgxNzgyNjU3NDg2OTE4MDk3OTg2 parts=101568niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1569niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.74s)1570niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed upload id=11571niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001572niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1573niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Starting cleanup of old closures method=DELETE path=/api/closures1574niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.74s)1575niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Aborted multipart uploads count=01576niks3-go-unit-tests> === NAME  TestClientCADerivations1577niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1578niks3-go-unit-tests> 2026/06/28 14:38:07 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=01579niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=pending_closures1580niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=pending_objects1581niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=multipart_uploads1582niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=closures1583niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=objects1584niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1585niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1586niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1587niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Signed narinfos id=2 count=11588niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 narinfos1589niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1590niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed upload id=21591niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Upload complete. (64ms)1592niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug1593niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:1594niks3-go-unit-tests>         StorePath: /build/TestNARDeduplicationMetadataUploadBug4191847718/001/store/4x4nzzpb3gshv3g60f7cvv4gsknd70kc-file2.txt1595niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1596niks3-go-unit-tests>         Compression: zstd1597niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1598niks3-go-unit-tests>         NarSize: 1601599niks3-go-unit-tests>         References: 1600niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1601niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1602niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1603niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}1604niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.78s)1605niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1606niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001607niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.79s)1608niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1609niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading wy77wp1z385fsmr5gnjdzkln9mz1nv7k-pinned-file.txt (128B)1610niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1611niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Signed narinfos id=1 count=11612niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 narinfos1613niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1614niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed upload id=11615niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Upload complete. (78ms)1616niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1617niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=YWZiNDk5OTktOTc4OC00MGM4LTg1ZmQtYTQzYTliZWEyZGEyLjBlZmU3OTE5LWY2YTQtNGIwNC04NjdhLTQ1MWJiOTM1YzNhZXgxNzgyNjU3NDg2OTMzNzAyMDI4 parts=121618niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.83s)1619niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1620niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1621niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading gpvbiw5qblp0yfxagk8qcswam6mi3wwh-ca-test (144B)1622niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1623niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Signed narinfos id=1 count=11624niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 narinfos1625niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1626niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed upload id=11627niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Upload complete. (72ms)1628niks3-go-unit-tests> === NAME  TestClientCADerivations1629niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations2463061933/001/store/gpvbiw5qblp0yfxagk8qcswam6mi3wwh-ca-test1630niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1631niks3-go-unit-tests>         Compression: zstd1632niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1633niks3-go-unit-tests>         NarSize: 1441634niks3-go-unit-tests>         References: 1635niks3-go-unit-tests>         Deriver: /build/TestClientCADerivations2463061933/001/store/qbrcsxq0jpngv9b01vayminngs8zp7zk-ca-test.drv1636niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1637niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1638niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.85s)1639niks3-go-unit-tests> === NAME  TestClientCADerivations1640niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1641niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1642niks3-go-unit-tests> 2026/06/28 14:38:07 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=721.851965ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1643niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received uploads request method=POST path=/api/pending_closures1644niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1645niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 37wks4bhhznpbpkbg2chn2z23svb6asw-unpinned-file.txt (128B)1646niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1647niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Signed narinfos id=2 count=11648niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Uploading 1 narinfos1649niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1650niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Completed upload id=21651niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Upload complete. (63ms)1652niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Received create pin request method=POST path=/api/pins/myapp1653niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC3930848558/001/store/wy77wp1z385fsmr5gnjdzkln9mz1nv7k-pinned-file.txt narinfo_key=wy77wp1z385fsmr5gnjdzkln9mz1nv7k.narinfo1654niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Starting cleanup of old closures method=DELETE path=/api/closures1655niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Garbage collection started1656niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Aborted multipart uploads count=01657niks3-go-unit-tests> 2026/06/28 14:38:07 WARN Force mode enabled - objects will be deleted immediately without grace period1658niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.22s)1659niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.04s)1660niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.04s)1661niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.76s)1662niks3-go-unit-tests> === NAME  TestClientCADerivations1663niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1664niks3-go-unit-tests>         warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1665niks3-go-unit-tests>         error: binary cache 's3://bucket43?endpoint=http://localhost:38625&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations2463061933/001/store'1666niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11667niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.97s)1668niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1669niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1670niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1671niks3-go-unit-tests> 2026/06/28 14:38:07 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=01672niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=pending_closures1673niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=pending_objects1674niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=multipart_uploads1675niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=closures1676niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=objects1677niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1678niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201679niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101680niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101681niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (1.23s)1682niks3-go-unit-tests> 2026/06/28 14:38:07 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=01683niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=pending_closures1684niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=pending_objects1685niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=multipart_uploads1686niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=closures1687niks3-go-unit-tests> 2026/06/28 14:38:07 INFO Vacuumed table table=objects1688niks3-go-unit-tests> 2026/06/28 14:38:08 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.735012839s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1689niks3-go-unit-tests> 2026/06/28 14:38:09 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01690niks3-go-unit-tests> === NAME  TestClientIntegration1691niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1692niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1693niks3-go-unit-tests> --- PASS: TestClientIntegration (2.71s)1694niks3-go-unit-tests> 2026/06/28 14:38:09 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01695niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1696niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1697niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.94s)1698niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)1699niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.51s)1700niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.63s)1701niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.32s)1702niks3-go-unit-tests> 2026/06/28 14:38:11 WARN Rate limiter enabled after throttle name=s3-test rate=51703niks3-go-unit-tests> 2026/06/28 14:38:11 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1704niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1705niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101706niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001707niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (5.32s)1708niks3-go-unit-tests> PASS1709niks3-go-unit-tests> 2026-06-28 14:38:12.427 UTC [334] LOG:  received smart shutdown request1710niks3-go-unit-tests> 2026-06-28 14:38:12.430 UTC [334] LOG:  background worker "logical replication launcher" (PID 341) exited with exit code 11711niks3-go-unit-tests> 2026-06-28 14:38:12.438 UTC [336] LOG:  shutting down1712niks3-go-unit-tests> 2026-06-28 14:38:12.438 UTC [336] LOG:  checkpoint starting: shutdown immediate1713niks3-go-unit-tests> 2026-06-28 14:38:13.727 UTC [336] LOG:  checkpoint complete: wrote 9011 buffers (55.0%); 0 WAL file(s) added, 0 removed, 12 recycled; write=0.155 s, sync=1.125 s, total=1.290 s; sync files=14421, longest=0.001 s, average=0.001 s; distance=194059 kB, estimate=194059 kB; lsn=0/D26F5E8, redo lsn=0/D26F5E81714niks3-go-unit-tests> 2026-06-28 14:38:13.801 UTC [334] LOG:  database system is shut down1715niks3-go-unit-tests> Running OIDC tests...1716niks3-go-unit-tests> === RUN   TestGlobMatch1717niks3-go-unit-tests> === PAUSE TestGlobMatch1718niks3-go-unit-tests> === RUN   TestAudienceForIssuer1719niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1720niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1721niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1722niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1723niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1724niks3-go-unit-tests> === RUN   TestValidateToken_Expired1725niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1726niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1727niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1728niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1729niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1730niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1731niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1732niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1733niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1734niks3-go-unit-tests> === CONT  TestGlobMatch1735niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1736niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1737niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1738niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1739niks3-go-unit-tests> === CONT  TestValidateToken_Expired1740niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1741niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1742niks3-go-unit-tests> === RUN   TestGlobMatch/*_1743niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1744niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1745niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1746niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1747niks3-go-unit-tests> === CONT  TestAudienceForIssuer1748niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1749niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1750niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1751niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1752niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1753niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1754niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1755niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1756niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1757niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1758niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1759niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1760niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1761niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1762niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1763niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1764niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1765niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1766niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1767niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1768niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1769niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1770niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1771niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1772niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1773niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1774niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1775niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1776niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01777niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01778niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1779niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1780niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1781niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1782niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1783niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1784niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1785niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1786niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1787niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1788niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1789niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1790niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1791niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1792niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1793niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1794niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1795niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1796niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1797niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1798niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1799niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1800niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1801niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1802niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1803niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1804niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1805niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1806niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1807niks3-go-unit-tests> === CONT  TestGlobMatch/*_1808niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1809niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1810niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1811niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1812niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01813niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1814niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1815niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1816niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1817niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1818niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1819niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1820niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1821niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1822niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1823niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1824niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1825niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1826niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1827niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1828niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1829niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1830niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1831niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1832niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1833niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1834niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1835niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1836niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1837niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1838niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1839niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1840niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1841niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1842niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1843niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1844niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1845niks3-go-unit-tests> 2026/06/28 14:38:14 INFO OIDC provider initialized name=test1846niks3-go-unit-tests> 2026/06/28 14:38:14 INFO OIDC provider initialized name=test1847niks3-go-unit-tests> 2026/06/28 14:38:14 INFO OIDC provider initialized name=provider11848niks3-go-unit-tests> 2026/06/28 14:38:14 INFO OIDC provider initialized name=provider11849niks3-go-unit-tests> 2026/06/28 14:38:14 INFO OIDC provider initialized name=test1850niks3-go-unit-tests> 2026/06/28 14:38:14 INFO OIDC provider initialized name=test1851niks3-go-unit-tests> 2026/06/28 14:38:14 INFO OIDC provider initialized name=test1852niks3-go-unit-tests> 2026/06/28 14:38:14 INFO OIDC provider initialized name=provider21853niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1854niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1855niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1856niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1857niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1858niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1859niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.01s)1860niks3-go-unit-tests> PASS1861niks3-go-unit-tests> Running hook tests...1862niks3-go-unit-tests> === RUN   TestSendPathsEmpty1863niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1864niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1865niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1866niks3-go-unit-tests> === RUN   TestQueueDeduplication1867niks3-go-unit-tests> === PAUSE TestQueueDeduplication1868niks3-go-unit-tests> === RUN   TestQueueRemove1869niks3-go-unit-tests> === PAUSE TestQueueRemove1870niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1871niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1872niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1873niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1874niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1875niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1876niks3-go-unit-tests> === RUN   TestServerClientIntegration1877niks3-go-unit-tests> === PAUSE TestServerClientIntegration1878niks3-go-unit-tests> === RUN   TestServerQueueError1879niks3-go-unit-tests> === PAUSE TestServerQueueError1880niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1881niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1882niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1883niks3-go-unit-tests>         PASS1884niks3-go-unit-tests>         1885niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.04s)1886niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1887niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1888niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1889niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1890niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1891niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1892niks3-go-unit-tests> === CONT  TestSendPathsEmpty1893niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1894niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1895niks3-go-unit-tests> === CONT  TestServerClientIntegration1896niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1897niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1898niks3-go-unit-tests> === CONT  TestQueueRemove1899niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1900niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1901niks3-go-unit-tests> === CONT  TestQueueDeduplication1902niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1903niks3-go-unit-tests> === CONT  TestServerQueueError1904niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1905niks3-go-unit-tests> 2026/06/28 14:38:14 ERROR Failed to queue paths error="permission denied" count=11906niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1907niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1908niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.02s)1909niks3-go-unit-tests> --- PASS: TestQueueRemove (0.02s)1910niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.02s)1911niks3-go-unit-tests> 2026/06/28 14:38:14 INFO Upload queue status pending=21912niks3-go-unit-tests> 2026/06/28 14:38:14 INFO Upload queue status pending=21913niks3-go-unit-tests> 2026/06/28 14:38:14 INFO Uploading batch count=11914niks3-go-unit-tests> 2026/06/28 14:38:14 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths2338056245/002/nonexistent1915niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.02s)1916niks3-go-unit-tests> 2026/06/28 14:38:14 INFO Upload queue status pending=21917niks3-go-unit-tests> 2026/06/28 14:38:14 INFO Uploading batch count=21918niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.02s)1919niks3-go-unit-tests> 2026/06/28 14:38:14 INFO Uploading batch count=11920niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.07s)1921niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.07s)1922niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.07s)1923niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.26s)1924niks3-go-unit-tests> PASS19251926post-build step Upload coverage to codecov: ok1927Skipping codecov: project=Mic92/niks3 attr=x86_64-linux.go-unit-tests