nixbot

1this derivation will be built:2  /nix/store/ghp03yx8k6mk3y0f3jzhp7pg0nn6nmvh-niks3-go-unit-tests.drv3building '/nix/store/ghp03yx8k6mk3y0f3jzhp7pg0nn6nmvh-niks3-go-unit-tests.drv' on 'ssh-ng://customer@mac02'4building '/nix/store/ghp03yx8k6mk3y0f3jzhp7pg0nn6nmvh-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  TestConvertHashToNix3276niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix3277niks3-go-unit-tests> === CONT  TestResolveStorePath78niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix3279niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format80niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format81niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format82niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess83niks3-go-unit-tests> === CONT  TestRateLimiterFeedback84niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter85niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter86niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter87niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter88niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter89niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter90niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter91niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter92niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility93niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field94niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field95niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text96niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text97niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive98niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive99niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text100niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text101niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method102niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method103niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths104niks3-go-unit-tests> 2026/06/28 12:21:56 WARN Rate limiter enabled after throttle name=server-test rate=5105niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths106niks3-go-unit-tests> === CONT  TestFileTokenMissing107niks3-go-unit-tests> === CONT  TestParsePathInfoJSON108niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format109niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format110niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format111niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format112niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input113niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input114niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility115niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only116niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only117niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON118niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON119niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)120niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)121niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon122niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon123niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI124niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI125niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails126niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand127niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)128niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON129niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths130niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths131niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths132niks3-go-unit-tests> === CONT  TestGetStorePathHash133niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path134niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path135niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error136niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error137niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error138niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error139niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken140niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error141niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error142niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format143niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh144niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.00s)145niks3-go-unit-tests> === CONT  TestFileTokenEmpty146niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512147niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall148niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512149niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer150niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists151niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists152niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing153niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing154niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix155niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)156niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport157niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.01s)158niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches159niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.01s)160niks3-go-unit-tests> === CONT  TestStaticToken161niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)162niks3-go-unit-tests> === CONT  TestSetClientTLSErrors163niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.00s)164niks3-go-unit-tests> === CONT  TestPartSizeForNAR165niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum166niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum167niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum168niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum169niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum170niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum171niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts172niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts173niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB174niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB175niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object176niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object177niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB178niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB179niks3-go-unit-tests> === CONT  TestCaseHackSuffix180niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file181niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file182niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file183niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file184niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file185niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file186niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file187niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file188niks3-go-unit-tests> === CONT  TestShellSplitErrors189niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)190niks3-go-unit-tests> === CONT  TestSetClientTLS191niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.01s)192niks3-go-unit-tests> === CONT  TestShellSplit193niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)194niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody195niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)196niks3-go-unit-tests> === CONT  TestEncodeNixBase32197niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash198niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash199niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input200niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input201niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash202niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)203niks3-go-unit-tests> === CONT  TestDumpPathWriterError204niks3-go-unit-tests> 2026/06/28 12:21:56 WARN Rate limiter enabled after throttle name=server-test rate=5205niks3-go-unit-tests> 2026/06/28 12:21:56 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:51945206niks3-go-unit-tests> 2026/06/28 12:21:56 WARN Rate limiter backed off name=server-test rate=5207niks3-go-unit-tests> 2026/06/28 12:21:56 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:51945208niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.00s)209niks3-go-unit-tests> === CONT  TestDumpPathSingleFile210niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert211niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert212niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA213niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA214niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport215niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport216niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter217niks3-go-unit-tests> 2026/06/28 12:21:56 WARN Rate limiter enabled after throttle name=server-test rate=5218niks3-go-unit-tests> 2026/06/28 12:21:56 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:51948219niks3-go-unit-tests> 2026/06/28 12:21:56 WARN Rate limiter backed off name=server-test rate=5220niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter221niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter222niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter223niks3-go-unit-tests> 2026/06/28 12:21:56 WARN Rate limiter enabled after throttle name=server-test rate=5224niks3-go-unit-tests> 2026/06/28 12:21:56 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:51954225niks3-go-unit-tests> 2026/06/28 12:21:56 WARN Rate limiter backed off name=server-test rate=5226niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.00s)227niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)228niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)229niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)230niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)231niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field232niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method233niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text234niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive235niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text236niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.00s)237niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)238niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)239niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)240niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)241niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)242niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format243niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only244niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON245niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format246niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input247niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)248niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)249niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)250niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)251niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)252niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)253niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths254niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path255niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths256niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)257niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)258niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)259niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error260niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32261niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error262niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error263niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)264niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)265niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)266niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)267niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)268niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format269niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format270niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.00s)271niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)272niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)273niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)274niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)275niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512276niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI277niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon278niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists279niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)280niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)281niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)282niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)283niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)284niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.02s)285niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing286niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.02s)287niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum288niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB289niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB290niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object291niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum292niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts293niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum294niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.00s)295niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)296niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)297niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)298niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)299niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)300niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)301niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)302niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file303niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file304niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file305niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)306niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)307niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)308niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file309niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash310niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input311niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport312niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)313niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)314niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)315niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert316niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA317niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.00s)318niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)319niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)320niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)321niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)322niks3-go-unit-tests> 2026/06/28 12:21:56 http: TLS handshake error from 127.0.0.1:51960: remote error: tls: bad certificate323niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.00s)324niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)325niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)326niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)327niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.04s)328niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.04s)329niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.04s)330niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.05s)331niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.05s)332niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.08s)333niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)334niks3-go-unit-tests> PASS335niks3-go-unit-tests> Running server tests...336niks3-go-unit-tests> The files belonging to this database system will be owned by user "_nixbld1".337niks3-go-unit-tests> This user must also own the server process.338niks3-go-unit-tests> 339niks3-go-unit-tests> The database cluster will be initialized with locale "C".340niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".341niks3-go-unit-tests> The default text search configuration will be set to "english".342niks3-go-unit-tests> 343niks3-go-unit-tests> Data page checksums are disabled.344niks3-go-unit-tests> 345niks3-go-unit-tests> creating directory /nix/var/nix/builds/nix-54969-1347812711/postgres2912348357/data ... ok346niks3-go-unit-tests> creating subdirectories ... ok347niks3-go-unit-tests> selecting dynamic shared memory implementation ... posix348niks3-go-unit-tests> selecting default "max_connections" ... 100349niks3-go-unit-tests> selecting default "shared_buffers" ... 128MB350niks3-go-unit-tests> selecting default time zone ... UTC351niks3-go-unit-tests> creating configuration files ... ok352niks3-go-unit-tests> running bootstrap script ... ok353niks3-go-unit-tests> performing post-bootstrap initialization ... ok354niks3-go-unit-tests> syncing data to disk ... ok355niks3-go-unit-tests> 356niks3-go-unit-tests> initdb: warning: enabling "trust" authentication for local connections357niks3-go-unit-tests> initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.358niks3-go-unit-tests> 359niks3-go-unit-tests> Success. You can now start the database server using:360niks3-go-unit-tests> 361niks3-go-unit-tests>     pg_ctl -D /nix/var/nix/builds/nix-54969-1347812711/postgres2912348357/data -l logfile start362niks3-go-unit-tests> 363niks3-go-unit-tests> /nix/var/nix/builds/nix-54969-1347812711/postgres2912348357:5432 - no response364niks3-go-unit-tests> 2026-06-28 12:21:58.457 UTC [55004] LOG:  starting PostgreSQL 17.10 on aarch64-apple-darwin25.4.0, compiled by clang version 21.1.8, 64-bit365niks3-go-unit-tests> 2026-06-28 12:21:58.457 UTC [55004] LOG:  listening on Unix socket "/nix/var/nix/builds/nix-54969-1347812711/postgres2912348357/.s.PGSQL.5432"366niks3-go-unit-tests> 2026-06-28 12:21:58.459 UTC [55008] LOG:  database system was shut down at 2026-06-28 12:21:58 UTC367niks3-go-unit-tests> 2026-06-28 12:21:58.460 UTC [55004] LOG:  database system is ready to accept connections368niks3-go-unit-tests> /nix/var/nix/builds/nix-54969-1347812711/postgres2912348357:5432 - accepting connections369niks3-go-unit-tests> <jemalloc>: option background_thread currently supports pthread only370niks3-go-unit-tests> {"timestamp":"2026-06-28T12:21:58.581843Z","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(4)"}371niks3-go-unit-tests> === RUN   TestService_AuthMiddleware372niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware373niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader374niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader375niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects376niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects377niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware378niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware379niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC380niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC381niks3-go-unit-tests> === RUN   TestCacheConfigHandler382niks3-go-unit-tests> === PAUSE TestCacheConfigHandler383niks3-go-unit-tests> === RUN   TestClientCADerivations384niks3-go-unit-tests> === PAUSE TestClientCADerivations385niks3-go-unit-tests> === RUN   TestClientErrorHandling386niks3-go-unit-tests> === PAUSE TestClientErrorHandling387niks3-go-unit-tests> === RUN   TestClientIntegration388niks3-go-unit-tests> === PAUSE TestClientIntegration389niks3-go-unit-tests> === RUN   TestClientMultipleUploads390niks3-go-unit-tests> === PAUSE TestClientMultipleUploads391niks3-go-unit-tests> === RUN   TestClientWithDependencies392niks3-go-unit-tests> === PAUSE TestClientWithDependencies393niks3-go-unit-tests> === RUN   TestPinProtectsFromGC394niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC395niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences396niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences397niks3-go-unit-tests> === RUN   TestGCMetrics398niks3-go-unit-tests> === PAUSE TestGCMetrics399niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew400niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew401niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams402niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams403niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams404niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams405niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty406niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty407niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest408niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest409niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask410niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask411niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates412niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates413niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail414niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail415niks3-go-unit-tests> === RUN   TestService_healthCheckHandler416niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler417niks3-go-unit-tests> === RUN   TestGenerateLandingPage418niks3-go-unit-tests> === PAUSE TestGenerateLandingPage419niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug420niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug421niks3-go-unit-tests> === RUN   TestMetricsInventory422niks3-go-unit-tests> === PAUSE TestMetricsInventory423niks3-go-unit-tests> === RUN   TestService_NativeMTLS424niks3-go-unit-tests> === PAUSE TestService_NativeMTLS425niks3-go-unit-tests> === RUN   TestServerTLSConfig426niks3-go-unit-tests> === PAUSE TestServerTLSConfig427niks3-go-unit-tests> === RUN   TestMultipartCleanup428niks3-go-unit-tests> === PAUSE TestMultipartCleanup429niks3-go-unit-tests> === RUN   TestObjectStatsTrigger430niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger431niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC432niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC433niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest434niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest435niks3-go-unit-tests> === RUN   TestResurrectedObjectNotDeleted436niks3-go-unit-tests> === PAUSE TestResurrectedObjectNotDeleted437niks3-go-unit-tests> === RUN   TestParseSingleRange438niks3-go-unit-tests> === PAUSE TestParseSingleRange439niks3-go-unit-tests> === RUN   TestIsValidCachePath440niks3-go-unit-tests> === PAUSE TestIsValidCachePath441niks3-go-unit-tests> === RUN   TestReadProxyNarinfo442niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo443niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed444niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed445niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming446niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming447niks3-go-unit-tests> === RUN   TestReadProxy404448niks3-go-unit-tests> === PAUSE TestReadProxy404449niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath450niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath451niks3-go-unit-tests> === RUN   TestReadProxyHead452niks3-go-unit-tests> === PAUSE TestReadProxyHead453niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet454niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet455niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML456niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML457niks3-go-unit-tests> === RUN   TestReadProxyDisabled458niks3-go-unit-tests> === PAUSE TestReadProxyDisabled459niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest460niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest461niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload462niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload463niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists464niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists465niks3-go-unit-tests> === RUN   TestService_Rustfstest466niks3-go-unit-tests> === PAUSE TestService_Rustfstest467niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle468niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle469niks3-go-unit-tests> === RUN   TestProxyWriteTimeout470niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout471niks3-go-unit-tests> === RUN   TestIsValidUploadKey472niks3-go-unit-tests> === PAUSE TestIsValidUploadKey473niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys474niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys475niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody476niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody477niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler478niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler479niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler480niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler481niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity482niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity483niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered484niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered485niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT486niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT487niks3-go-unit-tests> === CONT  TestService_AuthMiddleware488niks3-go-unit-tests> === CONT  TestObjectStatsTrigger489niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams490niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)491niks3-go-unit-tests> === CONT  TestMultipartCleanup492niks3-go-unit-tests> === CONT  TestReadProxyDisabled493niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML494niks3-go-unit-tests> === CONT  TestServerTLSConfig495niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet496niks3-go-unit-tests> === RUN   TestServerTLSConfig/no_client_CA497niks3-go-unit-tests> === CONT  TestReadProxyHead498niks3-go-unit-tests> === PAUSE TestServerTLSConfig/no_client_CA499niks3-go-unit-tests> === RUN   TestServerTLSConfig/missing_CA_file500niks3-go-unit-tests> === PAUSE TestServerTLSConfig/missing_CA_file501niks3-go-unit-tests> === RUN   TestServerTLSConfig/not_a_PEM_file502niks3-go-unit-tests> === PAUSE TestServerTLSConfig/not_a_PEM_file503niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming504niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath505niks3-go-unit-tests> === CONT  TestReadProxy404506niks3-go-unit-tests> 2026-06-28 12:21:58.942 UTC [55077] ERROR:  relation "goose_db_version" does not exist at character 36507niks3-go-unit-tests> 2026-06-28 12:21:58.942 UTC [55077] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC508niks3-go-unit-tests> 2026-06-28 12:21:58.950 UTC [55080] ERROR:  relation "goose_db_version" does not exist at character 36509niks3-go-unit-tests> 2026-06-28 12:21:58.950 UTC [55080] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC510niks3-go-unit-tests> 2026-06-28 12:21:58.950 UTC [55079] ERROR:  relation "goose_db_version" does not exist at character 36511niks3-go-unit-tests> 2026-06-28 12:21:58.950 UTC [55079] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC512niks3-go-unit-tests> 2026-06-28 12:21:58.950 UTC [55078] ERROR:  relation "goose_db_version" does not exist at character 36513niks3-go-unit-tests> 2026-06-28 12:21:58.950 UTC [55078] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC514niks3-go-unit-tests> 2026-06-28 12:21:58.950 UTC [55082] ERROR:  relation "goose_db_version" does not exist at character 36515niks3-go-unit-tests> 2026-06-28 12:21:58.950 UTC [55082] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC516niks3-go-unit-tests> 2026-06-28 12:21:58.950 UTC [55081] ERROR:  relation "goose_db_version" does not exist at character 36517niks3-go-unit-tests> 2026-06-28 12:21:58.950 UTC [55081] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC518niks3-go-unit-tests> 2026-06-28 12:21:58.952 UTC [55083] ERROR:  relation "goose_db_version" does not exist at character 36519niks3-go-unit-tests> 2026-06-28 12:21:58.952 UTC [55083] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC520niks3-go-unit-tests> 2026-06-28 12:21:58.954 UTC [55084] ERROR:  relation "goose_db_version" does not exist at character 36521niks3-go-unit-tests> 2026-06-28 12:21:58.954 UTC [55084] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC522niks3-go-unit-tests> 2026-06-28 12:21:58.954 UTC [55085] ERROR:  relation "goose_db_version" does not exist at character 36523niks3-go-unit-tests> 2026-06-28 12:21:58.954 UTC [55085] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC524niks3-go-unit-tests> 2026-06-28 12:21:58.954 UTC [55086] ERROR:  relation "goose_db_version" does not exist at character 36525niks3-go-unit-tests> 2026-06-28 12:21:58.954 UTC [55086] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC526niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20241026095416_initial_model.sql (7.01ms)527niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251210153512_drop_unused_gin_index.sql (694.25µs)528niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20241026095416_initial_model.sql (6.17ms)529niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251210153512_drop_unused_gin_index.sql (4.39ms)530niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20241026095416_initial_model.sql (7.46ms)531niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20241026095416_initial_model.sql (6.76ms)532niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20241026095416_initial_model.sql (10.21ms)533niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20241026095416_initial_model.sql (5.13ms)534niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20241026095416_initial_model.sql (10.55ms)535niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20241026095416_initial_model.sql (6.63ms)536niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251218171726_add_pins.sql (5.79ms)537niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20241026095416_initial_model.sql (9.27ms)538niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20241026095416_initial_model.sql (9.09ms)539niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251210153512_drop_unused_gin_index.sql (700.79µs)540niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251210153512_drop_unused_gin_index.sql (827.25µs)541niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251210153512_drop_unused_gin_index.sql (859.13µs)542niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251210153512_drop_unused_gin_index.sql (1.04ms)543niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251218171726_add_pins.sql (1.38ms)544niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251210153512_drop_unused_gin_index.sql (1.16ms)545niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251210153512_drop_unused_gin_index.sql (793µs)546niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251210153512_drop_unused_gin_index.sql (699.79µs)547niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251210153512_drop_unused_gin_index.sql (744.58µs)548niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20260628120000_add_object_size_and_stats.sql (1.44ms)549niks3-go-unit-tests> 2026/06/28 12:21:58 goose: successfully migrated database to version: 20260628120000550niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251218171726_add_pins.sql (1.11ms)551niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251218171726_add_pins.sql (1.22ms)552niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251218171726_add_pins.sql (1.41ms)553niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20260628120000_add_object_size_and_stats.sql (1.43ms)554niks3-go-unit-tests> 2026/06/28 12:21:58 goose: successfully migrated database to version: 20260628120000555niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251218171726_add_pins.sql (1.8ms)556niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251218171726_add_pins.sql (1.4ms)557niks3-go-unit-tests> 2026/06/28 12:21:58 OK   1_commit_pending_closure.sql (1.33ms)558niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251218171726_add_pins.sql (1.8ms)559niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251218171726_add_pins.sql (2ms)560niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20251218171726_add_pins.sql (1.84ms)561niks3-go-unit-tests> 2026/06/28 12:21:58 OK   2_object_stats_trigger.sql (397.13µs)562niks3-go-unit-tests> 2026/06/28 12:21:58 goose: up to current file version: 2563niks3-go-unit-tests> 2026/06/28 12:21:58 OK   1_commit_pending_closure.sql (954.04µs)564niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20260628120000_add_object_size_and_stats.sql (1.74ms)565niks3-go-unit-tests> 2026/06/28 12:21:58 goose: successfully migrated database to version: 20260628120000566niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20260628120000_add_object_size_and_stats.sql (1.55ms)567niks3-go-unit-tests> 2026/06/28 12:21:58 goose: successfully migrated database to version: 20260628120000568niks3-go-unit-tests> 2026/06/28 12:21:58 OK   2_object_stats_trigger.sql (497.17µs)569niks3-go-unit-tests> 2026/06/28 12:21:58 goose: up to current file version: 2570niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20260628120000_add_object_size_and_stats.sql (1.23ms)571niks3-go-unit-tests> 2026/06/28 12:21:58 goose: successfully migrated database to version: 20260628120000572niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20260628120000_add_object_size_and_stats.sql (1.34ms)573niks3-go-unit-tests> 2026/06/28 12:21:58 goose: successfully migrated database to version: 20260628120000574niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20260628120000_add_object_size_and_stats.sql (2.16ms)575niks3-go-unit-tests> 2026/06/28 12:21:58 goose: successfully migrated database to version: 20260628120000576niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20260628120000_add_object_size_and_stats.sql (1.58ms)577niks3-go-unit-tests> 2026/06/28 12:21:58 goose: successfully migrated database to version: 20260628120000578niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20260628120000_add_object_size_and_stats.sql (1.48ms)579niks3-go-unit-tests> 2026/06/28 12:21:58 goose: successfully migrated database to version: 20260628120000580niks3-go-unit-tests> 2026/06/28 12:21:58 OK   20260628120000_add_object_size_and_stats.sql (1.6ms)581niks3-go-unit-tests> 2026/06/28 12:21:58 goose: successfully migrated database to version: 20260628120000582niks3-go-unit-tests> 2026/06/28 12:21:58 OK   1_commit_pending_closure.sql (1.19ms)583niks3-go-unit-tests> 2026/06/28 12:21:58 OK   1_commit_pending_closure.sql (1.14ms)584niks3-go-unit-tests> 2026/06/28 12:21:58 OK   1_commit_pending_closure.sql (1.08ms)585niks3-go-unit-tests> 2026/06/28 12:21:58 OK   2_object_stats_trigger.sql (441.58µs)586niks3-go-unit-tests> 2026/06/28 12:21:58 goose: up to current file version: 2587niks3-go-unit-tests> 2026/06/28 12:21:58 OK   1_commit_pending_closure.sql (1.47ms)588niks3-go-unit-tests> 2026/06/28 12:21:58 OK   1_commit_pending_closure.sql (1.03ms)589niks3-go-unit-tests> 2026/06/28 12:21:58 OK   2_object_stats_trigger.sql (345.38µs)590niks3-go-unit-tests> 2026/06/28 12:21:58 goose: up to current file version: 2591niks3-go-unit-tests> 2026/06/28 12:21:58 OK   2_object_stats_trigger.sql (437.38µs)592niks3-go-unit-tests> 2026/06/28 12:21:58 goose: up to current file version: 2593niks3-go-unit-tests> 2026/06/28 12:21:58 OK   1_commit_pending_closure.sql (1.07ms)594niks3-go-unit-tests> 2026/06/28 12:21:58 OK   2_object_stats_trigger.sql (353.58µs)595niks3-go-unit-tests> 2026/06/28 12:21:58 goose: up to current file version: 2596niks3-go-unit-tests> 2026/06/28 12:21:58 OK   2_object_stats_trigger.sql (381.21µs)597niks3-go-unit-tests> 2026/06/28 12:21:58 goose: up to current file version: 2598niks3-go-unit-tests> 2026/06/28 12:21:58 OK   1_commit_pending_closure.sql (1.32ms)599niks3-go-unit-tests> 2026/06/28 12:21:58 OK   2_object_stats_trigger.sql (376.08µs)600niks3-go-unit-tests> 2026/06/28 12:21:58 goose: up to current file version: 2601niks3-go-unit-tests> 2026/06/28 12:21:58 OK   1_commit_pending_closure.sql (1.46ms)602niks3-go-unit-tests> 2026/06/28 12:21:58 OK   2_object_stats_trigger.sql (386.54µs)603niks3-go-unit-tests> 2026/06/28 12:21:58 goose: up to current file version: 2604niks3-go-unit-tests> 2026/06/28 12:21:58 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"605niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (0.30s)606niks3-go-unit-tests> === CONT  TestServerTLSConfig/no_client_CA607niks3-go-unit-tests> === CONT  TestService_NativeMTLS608niks3-go-unit-tests> 2026/06/28 12:21:58 OK   2_object_stats_trigger.sql (510.63µs)609niks3-go-unit-tests> 2026/06/28 12:21:58 goose: up to current file version: 2610niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (0.30s)611niks3-go-unit-tests> === CONT  TestMetricsInventory612niks3-go-unit-tests> 2026/06/28 12:21:58 INFO Received uploads request method=POST path=/api/pending_closures613niks3-go-unit-tests> {"timestamp":"2026-06-28T12:21:58.974564Z","level":"ERROR","fields":{"message":"system path read failed","path_kind":"data_usage","operation":"read_primary","reason":"config_not_found","object":"buckets/.usage.json","error":"Config not found"},"target":"rustfs_ecstore::data_usage","filename":"crates/ecstore/src/data_usage.rs","line_number":126,"threadName":"rustfs-worker","threadId":"ThreadId(5)"}614niks3-go-unit-tests> --- PASS: TestReadProxy404 (0.30s)615niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug616niks3-go-unit-tests> --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.30s)617niks3-go-unit-tests> === CONT  TestGenerateLandingPage618niks3-go-unit-tests> --- PASS: TestReadProxyInvalidPath (0.30s)619niks3-go-unit-tests> === CONT  TestService_healthCheckHandler620niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)621niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail622niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)623niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates624niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)625niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask626niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)627niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest628niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)629niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty630niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)631niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams632niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)633niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed634niks3-go-unit-tests> --- PASS: TestObjectStatsTrigger (0.30s)635niks3-go-unit-tests> === CONT  TestReadProxyNarinfo636niks3-go-unit-tests> --- PASS: TestReadProxyNarStreaming (0.31s)637niks3-go-unit-tests> === CONT  TestIsValidCachePath638niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo639niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo640niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars641niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars642niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst643niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst644niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz645niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz646niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz2647niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz2648niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed649niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed650niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls651niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls652niks3-go-unit-tests> === RUN   TestIsValidCachePath/log653niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log654niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation655niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation656niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info657niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info658niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html659niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html660niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent661niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent662niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle663niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle664niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e665niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e666niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u667niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u668niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path669niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path670niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty671niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty672niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash673niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash674niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension675niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension676niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash677niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash678niks3-go-unit-tests> === CONT  TestParseSingleRange679niks3-go-unit-tests> === RUN   TestParseSingleRange/none680niks3-go-unit-tests> === PAUSE TestParseSingleRange/none681niks3-go-unit-tests> === RUN   TestParseSingleRange/unknown_unit682niks3-go-unit-tests> === PAUSE TestParseSingleRange/unknown_unit683niks3-go-unit-tests> === RUN   TestParseSingleRange/multi-range_ignored684niks3-go-unit-tests> === PAUSE TestParseSingleRange/multi-range_ignored685niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_no_dash686niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_no_dash687niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_both_empty688niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_both_empty689niks3-go-unit-tests> === RUN   TestParseSingleRange/malformed_end_before_start690niks3-go-unit-tests> === PAUSE TestParseSingleRange/malformed_end_before_start691niks3-go-unit-tests> === RUN   TestParseSingleRange/closed692niks3-go-unit-tests> === PAUSE TestParseSingleRange/closed693niks3-go-unit-tests> === RUN   TestParseSingleRange/open-ended694niks3-go-unit-tests> === PAUSE TestParseSingleRange/open-ended695niks3-go-unit-tests> === RUN   TestParseSingleRange/end_clamped_to_size696niks3-go-unit-tests> === PAUSE TestParseSingleRange/end_clamped_to_size697niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix698niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix699niks3-go-unit-tests> === RUN   TestParseSingleRange/suffix_exceeds_size700niks3-go-unit-tests> === PAUSE TestParseSingleRange/suffix_exceeds_size701niks3-go-unit-tests> === RUN   TestParseSingleRange/single_byte702niks3-go-unit-tests> === PAUSE TestParseSingleRange/single_byte703niks3-go-unit-tests> === RUN   TestParseSingleRange/start_past_EOF704niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_past_EOF705niks3-go-unit-tests> === RUN   TestParseSingleRange/start_far_past_EOF706niks3-go-unit-tests> === PAUSE TestParseSingleRange/start_far_past_EOF707niks3-go-unit-tests> === CONT  TestResurrectedObjectNotDeleted708niks3-go-unit-tests> --- PASS: TestReadProxyHead (0.31s)709niks3-go-unit-tests> === CONT  TestOrphanedObjectsGCStressTest710niks3-go-unit-tests> --- PASS: TestReadProxyConditionalGet (0.31s)711niks3-go-unit-tests> === CONT  TestOrphanedObjectsGC712niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received cleanup request method=DELETE path=/api/pending_closures713niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Aborted multipart uploads count=1714niks3-go-unit-tests> --- PASS: TestMultipartCleanup (0.42s)715niks3-go-unit-tests> === CONT  TestServerTLSConfig/not_a_PEM_file716niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys717niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info718niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info719niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal720niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal721niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key722niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key723niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key724niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key725niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT726niks3-go-unit-tests> 2026-06-28 12:21:59.225 UTC [55128] ERROR:  relation "goose_db_version" does not exist at character 36727niks3-go-unit-tests> 2026-06-28 12:21:59.225 UTC [55128] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC728niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (11.43ms)729niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (387.92µs)730niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (693.25µs)731niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (6.91ms)732niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 20260628120000733niks3-go-unit-tests> 2026-06-28 12:21:59.254 UTC [55129] ERROR:  relation "goose_db_version" does not exist at character 36734niks3-go-unit-tests> 2026-06-28 12:21:59.254 UTC [55129] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC735niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.53ms)736niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (240.21µs)737niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 2738niks3-go-unit-tests> 2026-06-28 12:21:59.261 UTC [55130] ERROR:  relation "goose_db_version" does not exist at character 36739niks3-go-unit-tests> 2026-06-28 12:21:59.261 UTC [55130] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC740niks3-go-unit-tests> --- PASS: TestMetricsInventory (0.30s)741niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered742niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (10.32ms)743niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (533.67µs)744niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (1.7ms)745niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (16.23ms)746niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (571.96µs)747niks3-go-unit-tests> 2026-06-28 12:21:59.287 UTC [55133] ERROR:  relation "goose_db_version" does not exist at character 36748niks3-go-unit-tests> 2026-06-28 12:21:59.287 UTC [55133] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC749niks3-go-unit-tests> 2026-06-28 12:21:59.288 UTC [55134] ERROR:  relation "goose_db_version" does not exist at character 36750niks3-go-unit-tests> 2026-06-28 12:21:59.288 UTC [55134] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC751niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (1.71ms)752niks3-go-unit-tests> 2026-06-28 12:21:59.288 UTC [55135] ERROR:  relation "goose_db_version" does not exist at character 36753niks3-go-unit-tests> 2026-06-28 12:21:59.288 UTC [55135] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC754niks3-go-unit-tests> 2026-06-28 12:21:59.289 UTC [55136] ERROR:  relation "goose_db_version" does not exist at character 36755niks3-go-unit-tests> 2026-06-28 12:21:59.289 UTC [55136] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC756niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (27.07ms)757niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 20260628120000758niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (18.76ms)759niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 20260628120000760niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.1ms)761niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (357.38µs)762niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 2763niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.04ms)764niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (261.83µs)765niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 2766niks3-go-unit-tests> 2026/06/28 12:21:59 WARN mTLS auth: subject not in bound subjects subject="CN=reader"767niks3-go-unit-tests> 2026/06/28 12:21:59 WARN mTLS auth: subject not in bound subjects subject="CN=writer"768niks3-go-unit-tests> --- PASS: TestService_NativeMTLS (0.34s)769niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity770niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Created nix-cache-info in bucket bucket=bucket12771niks3-go-unit-tests> 2026-06-28 12:21:59.316 UTC [55139] ERROR:  relation "goose_db_version" does not exist at character 36772niks3-go-unit-tests> 2026-06-28 12:21:59.316 UTC [55139] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC773niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (14.1ms)774niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (598.92µs)775niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (14.71ms)776niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (1.92ms)777niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (15.21ms)778niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (555.83µs)779niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (712.5µs)780niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (1.57ms)781niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 20260628120000782niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (1.99ms)783niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (1.52ms)784niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (8.41ms)785niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.28ms)786niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (719.46µs)787niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (971.75µs)788niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 20260628120000789niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (527.17µs)790niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 2791niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (8.04ms)792niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (959.42µs)793niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (981.29µs)794niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (508.42µs)795niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (425.92µs)796niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 2797niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (0.35s)798niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler799niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (754.5µs)800niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (1.18ms)801niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 20260628120000802niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (4.14ms)803niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 20260628120000804niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (2.56ms)805niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 20260628120000806niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.23ms)807niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.55ms)808niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (535.67µs)809niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 2810niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (493.21µs)811niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 2812niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.88ms)813niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (301.46µs)814niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 2815niks3-go-unit-tests> --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.36s)816niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler817niks3-go-unit-tests> --- PASS: TestReadProxyNarinfo (0.36s)818niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody819niks3-go-unit-tests> 2026-06-28 12:21:59.342 UTC [55144] ERROR:  relation "goose_db_version" does not exist at character 36820niks3-go-unit-tests> 2026-06-28 12:21:59.342 UTC [55144] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC821niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure822niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure823niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart824niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart825niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts826niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts827niks3-go-unit-tests> === CONT  TestServerTLSConfig/missing_CA_file828niks3-go-unit-tests> --- PASS: TestServerTLSConfig (0.00s)829niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)830niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)831niks3-go-unit-tests>     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)832niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle833niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug834niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /nix/var/nix/builds/nix-54969-1347812711/TestNARDeduplicationMetadataUploadBug2610066906/001/store/ixi87i5fnwyhydlimivh84i135dy0ahz-file1.txt835niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (19.9ms)836niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (580.21µs)837niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (763.75µs)838niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (3.46ms)839niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 20260628120000840niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.08ms)841niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (272.21µs)842niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 2843niks3-go-unit-tests> --- PASS: TestResurrectedObjectNotDeleted (0.46s)844niks3-go-unit-tests> === CONT  TestIsValidUploadKey845niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo846niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo847niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst848niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst849niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz850niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz851niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain852niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain853niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing854niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing855niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log856niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log857niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file858niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file859niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name860niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name861niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark862niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark863niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals864niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals865niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation866niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation867niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output868niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output869niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info870niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info871niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html872niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html873niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type874niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type875niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type876niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type877niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type878niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type879niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal880niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal881niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar882niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar883niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute884niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute885niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key886niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key887niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type888niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type889niks3-go-unit-tests> === CONT  TestProxyWriteTimeout890niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo891niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo892niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar893niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar894niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar895niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar896niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size897niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size898niks3-go-unit-tests> === CONT  TestClientIntegration899niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received uploads request method=POST path=/api/pending_closures900niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)901niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Uploading ixi87i5fnwyhydlimivh84i135dy0ahz-file1.txt (160B)902niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign903niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Signed narinfos id=1 count=1904niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Uploading 1 narinfos905niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete906niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Completed upload id=1907niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Upload complete. (85ms)908niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug909niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:910niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-54969-1347812711/TestNARDeduplicationMetadataUploadBug2610066906/001/store/ixi87i5fnwyhydlimivh84i135dy0ahz-file1.txt911niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst912niks3-go-unit-tests>         Compression: zstd913niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf914niks3-go-unit-tests>         NarSize: 160915niks3-go-unit-tests>         References: 916niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf917niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)918niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):919niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}920niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /nix/var/nix/builds/nix-54969-1347812711/TestNARDeduplicationMetadataUploadBug2610066906/001/store/c3afpjw8cjvlism5a9z24b10wygjgh9z-file2.txt921niks3-go-unit-tests> === NAME  TestOrphanedObjectsGC922niks3-go-unit-tests>     orphaned_objects_gc_test.go:290: GC Test Summary:923niks3-go-unit-tests>     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A924niks3-go-unit-tests>     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B925niks3-go-unit-tests>     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)926niks3-go-unit-tests>     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)927niks3-go-unit-tests>     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects928niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGC (0.65s)929niks3-go-unit-tests> === CONT  TestGCTaskStore_StartNew930niks3-go-unit-tests> --- PASS: TestGCTaskStore_StartNew (0.00s)931niks3-go-unit-tests> === CONT  TestGCMetrics932niks3-go-unit-tests> 2026-06-28 12:21:59.637 UTC [55165] ERROR:  relation "goose_db_version" does not exist at character 36933niks3-go-unit-tests> 2026-06-28 12:21:59.637 UTC [55165] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC934niks3-go-unit-tests> 2026-06-28 12:21:59.658 UTC [55168] ERROR:  relation "goose_db_version" does not exist at character 36935niks3-go-unit-tests> 2026-06-28 12:21:59.658 UTC [55168] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC936niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received uploads request method=POST path=/api/pending_closures937niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)938niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign939niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Signed narinfos id=2 count=1940niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Uploading 1 narinfos941niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete942niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Completed upload id=2943niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Upload complete. (81ms)944niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug945niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:946niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-54969-1347812711/TestNARDeduplicationMetadataUploadBug2610066906/001/store/c3afpjw8cjvlism5a9z24b10wygjgh9z-file2.txt947niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst948niks3-go-unit-tests>         Compression: zstd949niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf950niks3-go-unit-tests>         NarSize: 160951niks3-go-unit-tests>         References: 952niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf953niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)954niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):955niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}956niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (0.70s)957niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences958niks3-go-unit-tests> 2026-06-28 12:21:59.673 UTC [55169] ERROR:  relation "goose_db_version" does not exist at character 36959niks3-go-unit-tests> 2026-06-28 12:21:59.673 UTC [55169] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC960niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (27.65ms)961niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (738.29µs)962niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (884.04µs)963niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (15.67ms)964niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (362.54µs)965niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (722.13µs)966niks3-go-unit-tests> 2026-06-28 12:21:59.687 UTC [55172] ERROR:  relation "goose_db_version" does not exist at character 36967niks3-go-unit-tests> 2026-06-28 12:21:59.687 UTC [55172] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC968niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (16.71ms)969niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 20260628120000970niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (11.34ms)971niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 20260628120000972niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.87ms)973niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.38ms)974niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (396.29µs)975niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 2976niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (323.75µs)977niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 2978niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received complete multipart upload request method=POST path=/api/multipart/complete979niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received uploads request method=POST path=/api/pending_closures980niks3-go-unit-tests> 2026/06/28 12:21:59 ERROR Multipart upload not found error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst981niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (0.43s)982niks3-go-unit-tests> === CONT  TestPinProtectsFromGC983niks3-go-unit-tests> 2026-06-28 12:21:59.703 UTC [55174] ERROR:  relation "goose_db_version" does not exist at character 36984niks3-go-unit-tests> 2026-06-28 12:21:59.703 UTC [55174] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC985niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (32.24ms)986niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (961.63µs)987niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (3.18ms)988niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.62s)989niks3-go-unit-tests> === CONT  TestClientWithDependencies990niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (22.11ms)991niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (3.65ms)992niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 20260628120000993niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (568.5µs)994niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.59ms)995niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (326.29µs)996niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 2997niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received uploads request method=POST path=/api/pending_closures998niks3-go-unit-tests> 2026-06-28 12:21:59.727 UTC [55177] ERROR:  relation "goose_db_version" does not exist at character 36999niks3-go-unit-tests> 2026-06-28 12:21:59.727 UTC [55177] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1000niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (10.7ms)1001niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (25.42ms)1002niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (9.27ms)1003niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 202606281200001004niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (773.13µs)1005niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.27ms)1006niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (236.63µs)1007niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 21008niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (1.56ms)1009niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received uploads request method=POST path=/api/pending_closures1010niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received uploads request method=POST path=/api/pending_closures1011niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received uploads request method=POST path=/api/pending_closures1012niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (8.24ms)1013niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (6.01ms)1014niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 202606281200001015niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (1.21ms)1016niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (1.56ms)1017niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (556.63µs)1018niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 21019niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (1.4ms)1020niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received cleanup request method=DELETE path=/api/pending_closures1021niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (2.79ms)1022niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 202606281200001023niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Aborted multipart uploads count=01024niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received uploads request method=POST path=/api/pending_closures1025niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (2.43ms)1026niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (315.96µs)1027niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 21028niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received uploads request method=POST path=/api/pending_closures1029niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received cleanup request method=DELETE path=/api/pending_closures1030niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Aborted multipart uploads count=11031niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1032niks3-go-unit-tests> 2026-06-28 12:21:59.764 UTC [55174] ERROR:  Closure does not exist: id=11033niks3-go-unit-tests> 2026-06-28 12:21:59.764 UTC [55174] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE1034niks3-go-unit-tests> 2026-06-28 12:21:59.764 UTC [55174] STATEMENT:  -- name: CommitPendingClosure :exec1035niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1036niks3-go-unit-tests> 	1037niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (0.43s)1038niks3-go-unit-tests> === CONT  TestClientMultipleUploads1039niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1040niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1041niks3-go-unit-tests>     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1042niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1043niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=YWM1ZThmMTItMjg0Mi00YTUyLThiYzItOTg3ZWEwMDI2MDUwLmNmMzkyYmY2LTFiYzgtNGViNi1hZDI1LTk0MGUyOTMwNTkzZHgxNzgyNjQ5MzE5NzQwOTc2MDAw parts=101044niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1045niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1046niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=YWM1ZThmMTItMjg0Mi00YTUyLThiYzItOTg3ZWEwMDI2MDUwLmJlYTU5Yjk4LTU3YTUtNDFjMi04Y2IxLTQzMTljM2E5MTI0MngxNzgyNjQ5MzE5NzUwMDE1MDAw parts=101047niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1048niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Completed upload id=11049niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001050niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Completed upload id=11051niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received uploads request method=POST path=/api/pending_closures1052niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received uploads request method=POST path=/api/pending_closures1053niks3-go-unit-tests>     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1054niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received uploads request method=POST path=/api/pending_closures1055niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Starting cleanup of old closures method=DELETE path=/api/closures1056niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Aborted multipart uploads count=01057niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1058niks3-go-unit-tests> 2026/06/28 12:21:59 WARN Found objects in DB but missing from S3, will re-upload count=11059niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (0.55s)1060niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists1061niks3-go-unit-tests> 2026/06/28 12:21:59 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=01062niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Vacuumed table table=pending_closures1063niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Vacuumed table table=pending_objects1064niks3-go-unit-tests> 2026-06-28 12:21:59.875 UTC [55183] ERROR:  relation "goose_db_version" does not exist at character 361065niks3-go-unit-tests> 2026-06-28 12:21:59.875 UTC [55183] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1066niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Vacuumed table table=multipart_uploads1067niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Vacuumed table table=closures1068niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Vacuumed table table=objects1069niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20241026095416_initial_model.sql (9.9ms)1070niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251210153512_drop_unused_gin_index.sql (798.54µs)1071niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001072niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (0.58s)1073niks3-go-unit-tests> === CONT  TestService_Rustfstest1074niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20251218171726_add_pins.sql (2.97ms)1075niks3-go-unit-tests> 2026/06/28 12:21:59 OK   20260628120000_add_object_size_and_stats.sql (2.99ms)1076niks3-go-unit-tests> 2026/06/28 12:21:59 goose: successfully migrated database to version: 202606281200001077niks3-go-unit-tests> 2026/06/28 12:21:59 OK   1_commit_pending_closure.sql (903.71µs)1078niks3-go-unit-tests> 2026/06/28 12:21:59 OK   2_object_stats_trigger.sql (202.88µs)1079niks3-go-unit-tests> 2026/06/28 12:21:59 goose: up to current file version: 21080niks3-go-unit-tests> 2026/06/28 12:21:59 INFO Created nix-cache-info in bucket bucket=bucket261081niks3-go-unit-tests> === NAME  TestOrphanedObjectsGCStressTest1082niks3-go-unit-tests>     orphaned_objects_gc_test.go:509: Stress test completed successfully:1083niks3-go-unit-tests>     orphaned_objects_gc_test.go:510:   - Active objects preserved: 201084niks3-go-unit-tests>     orphaned_objects_gc_test.go:511:   - Objects deleted: 2101085niks3-go-unit-tests>     orphaned_objects_gc_test.go:512:   - Total GC'd: 2101086niks3-go-unit-tests> --- PASS: TestOrphanedObjectsGCStressTest (0.98s)1087niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC1088niks3-go-unit-tests> 2026/06/28 12:21:59 INFO OIDC provider initialized name=test1089niks3-go-unit-tests> === NAME  TestClientIntegration1090niks3-go-unit-tests>     client_integration_test.go:276: Created store path: /nix/var/nix/builds/nix-54969-1347812711/TestClientIntegration1900708074/002/store/vl7n8zgx46wmmlizc1k05q43215z59bf-test-file.txt1091niks3-go-unit-tests> 2026-06-28 12:22:00.057 UTC [55197] ERROR:  relation "goose_db_version" does not exist at character 361092niks3-go-unit-tests> 2026-06-28 12:22:00.057 UTC [55197] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1093niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (8.56ms)1094niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (418.96µs)1095niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (729.83µs)1096niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (5.29ms)1097niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001098niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (1.6ms)1099niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (383.08µs)1100niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21101niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Aborted multipart uploads count=01102niks3-go-unit-tests> 2026/06/28 12:22:00 WARN Force mode enabled - objects will be deleted immediately without grace period1103niks3-go-unit-tests> 2026/06/28 12:22:00 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=01104niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=pending_closures1105niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=pending_objects1106niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=multipart_uploads1107niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=closures1108niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=objects1109niks3-go-unit-tests> --- PASS: TestGCMetrics (0.46s)1110niks3-go-unit-tests> === CONT  TestClientErrorHandling1111niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidStorePath1112niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidStorePath1113niks3-go-unit-tests> === RUN   TestClientErrorHandling/InvalidAuthToken1114niks3-go-unit-tests> === PAUSE TestClientErrorHandling/InvalidAuthToken1115niks3-go-unit-tests> === RUN   TestClientErrorHandling/ServerNotAvailable1116niks3-go-unit-tests> === PAUSE TestClientErrorHandling/ServerNotAvailable1117niks3-go-unit-tests> === CONT  TestClientCADerivations1118niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/api/pending_closures1119niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1120niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading vl7n8zgx46wmmlizc1k05q43215z59bf-test-file.txt (152B)1121niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1122niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Signed narinfos id=1 count=11123niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 1 narinfos1124niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1125niks3-go-unit-tests> 2026-06-28 12:22:00.120 UTC [55202] ERROR:  relation "goose_db_version" does not exist at character 361126niks3-go-unit-tests> 2026-06-28 12:22:00.120 UTC [55202] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1127niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Completed upload id=11128niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Upload complete. (95ms)1129niks3-go-unit-tests> === NAME  TestClientIntegration1130niks3-go-unit-tests>     client_integration_test.go:292: Retrieved narinfo from S3:1131niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-54969-1347812711/TestClientIntegration1900708074/002/store/vl7n8zgx46wmmlizc1k05q43215z59bf-test-file.txt1132niks3-go-unit-tests>         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1133niks3-go-unit-tests>         Compression: zstd1134niks3-go-unit-tests>         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11135niks3-go-unit-tests>         NarSize: 1521136niks3-go-unit-tests>         References: 1137niks3-go-unit-tests>         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11138niks3-go-unit-tests>     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)1139niks3-go-unit-tests>     client_integration_test.go:293: Decompressed .ls content (64 bytes):1140niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1141niks3-go-unit-tests>     client_integration_test.go:296: Testing garbage collection...1142niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (9.24ms)1143niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (902.63µs)1144niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (1.32ms)1145niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (5.26ms)1146niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001147niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (1.01ms)1148niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (208.29µs)1149niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21150niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Created nix-cache-info in bucket bucket=bucket281151niks3-go-unit-tests> 2026-06-28 12:22:00.154 UTC [55205] ERROR:  relation "goose_db_version" does not exist at character 361152niks3-go-unit-tests> 2026-06-28 12:22:00.154 UTC [55205] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1153niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Starting cleanup of old closures method=DELETE path=/api/closures1154niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Garbage collection started1155niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Aborted multipart uploads count=01156niks3-go-unit-tests> 2026/06/28 12:22:00 WARN Force mode enabled - objects will be deleted immediately without grace period1157niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (8.19ms)1158niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (644.13µs)1159niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (651.42µs)1160niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (3.26ms)1161niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001162niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (928.92µs)1163niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (278.21µs)1164niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21165niks3-go-unit-tests> 2026-06-28 12:22:00.202 UTC [55208] ERROR:  relation "goose_db_version" does not exist at character 361166niks3-go-unit-tests> 2026-06-28 12:22:00.202 UTC [55208] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1167niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (6.36ms)1168niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (460.46µs)1169niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (781.42µs)1170niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (3.58ms)1171niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001172niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (1.7ms)1173niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (197.54µs)1174niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21175niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Created nix-cache-info in bucket bucket=bucket301176niks3-go-unit-tests> 2026-06-28 12:22:00.249 UTC [55212] ERROR:  relation "goose_db_version" does not exist at character 361177niks3-go-unit-tests> 2026-06-28 12:22:00.249 UTC [55212] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1178niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1179niks3-go-unit-tests>     client_integration_test.go:646: Pinned store path: /nix/var/nix/builds/nix-54969-1347812711/TestPinProtectsFromGC2077977858/001/store/39bh0l82p8qjk67hh8d48rsprinq6nzc-pinned-file.txt1180niks3-go-unit-tests>     client_integration_test.go:647: Unpinned store path: /nix/var/nix/builds/nix-54969-1347812711/TestPinProtectsFromGC2077977858/001/store/dpygddiz6h22k836mhrw4c8g8l7nrhd4-unpinned-file.txt1181niks3-go-unit-tests> 2026-06-28 12:22:00.252 UTC [55213] ERROR:  relation "goose_db_version" does not exist at character 361182niks3-go-unit-tests> 2026-06-28 12:22:00.252 UTC [55213] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1183niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (25.79ms)1184niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (469.67µs)1185niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (27.82ms)1186niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (1.31ms)1187niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (561.63µs)1188niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (1.32ms)1189niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001190niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (3.36ms)1191niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (4.49ms)1192niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (535.17µs)1193niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21194niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (1.88ms)1195niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001196niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (1.83ms)1197niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (372.71µs)1198niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21199niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Created nix-cache-info in bucket bucket=bucket311200niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/api/pending_closures1201niks3-go-unit-tests> 2026-06-28 12:22:00.309 UTC [55220] ERROR:  relation "goose_db_version" does not exist at character 361202niks3-go-unit-tests> 2026-06-28 12:22:00.309 UTC [55220] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1203niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1204niks3-go-unit-tests> {"timestamp":"2026-06-28T12:22:00.320592Z","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(10)"}1205niks3-go-unit-tests> {"timestamp":"2026-06-28T12:22:00.320624Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket32, 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(10)"}1206niks3-go-unit-tests> 2026/06/28 12:22:00 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=YWM1ZThmMTItMjg0Mi00YTUyLThiYzItOTg3ZWEwMDI2MDUwLjAwMmM1YmEzLTIxY2MtNGYyMC04NzAwLTdjNWQ4MjU2YTBhOHgxNzgyNjQ5MzIwMzA2NTkyMDAw1207niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=YWM1ZThmMTItMjg0Mi00YTUyLThiYzItOTg3ZWEwMDI2MDUwLjAwMmM1YmEzLTIxY2MtNGYyMC04NzAwLTdjNWQ4MjU2YTBhOHgxNzgyNjQ5MzIwMzA2NTkyMDAw parts=11208niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.47s)1209niks3-go-unit-tests> === CONT  TestCacheConfigHandler1210niks3-go-unit-tests> === RUN   TestCacheConfigHandler/full_config,_no_issuer1211niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/full_config,_no_issuer1212niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_cache_url_configured1213niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_cache_url_configured1214niks3-go-unit-tests> === RUN   TestCacheConfigHandler/no_signing_keys1215niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/no_signing_keys1216niks3-go-unit-tests> === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1217niks3-go-unit-tests> === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1218niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSBoundSubjects1219niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (14.78ms)1220niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (780.13µs)1221niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (1.33ms)1222niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (13.44ms)1223niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001224niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (7.13ms)1225niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (241.71µs)1226niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21227niks3-go-unit-tests> --- PASS: TestService_Rustfstest (0.45s)1228niks3-go-unit-tests> === CONT  TestService_ReadAuthMiddleware1229niks3-go-unit-tests> === NAME  TestClientMultipleUploads1230niks3-go-unit-tests>     client_integration_test.go:338: Created store path 0: /nix/var/nix/builds/nix-54969-1347812711/TestClientMultipleUploads1343215351/001/store/hr1r330kjch511npgvf300rsxqnxrc3w-test-file-0.txt1231niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/api/pending_closures1232niks3-go-unit-tests> 2026-06-28 12:22:00.362 UTC [55232] ERROR:  relation "goose_db_version" does not exist at character 361233niks3-go-unit-tests> 2026-06-28 12:22:00.362 UTC [55232] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1234niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1235niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 39bh0l82p8qjk67hh8d48rsprinq6nzc-pinned-file.txt (128B)1236niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1237niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Signed narinfos id=1 count=11238niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 1 narinfos1239niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1240niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Completed upload id=11241niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Upload complete. (91ms)1242niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (6.2ms)1243niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (988.04µs)1244niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (2.63ms)1245niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (2.73ms)1246niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001247niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (5.22ms)1248niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (519.42µs)1249niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21250niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token1251niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1252niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1253niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1254niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected1255niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1256niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1257niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1258niks3-go-unit-tests> === CONT  TestRedundantMultipartUpload1259niks3-go-unit-tests> === NAME  TestClientMultipleUploads1260niks3-go-unit-tests>     client_integration_test.go:338: Created store path 1: /nix/var/nix/builds/nix-54969-1347812711/TestClientMultipleUploads1343215351/001/store/495n54ywzmv5knfym5yal6pgham4j2db-test-file-1.txt1261niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (0.75s)1262niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_MTLSProxyHeader1263niks3-go-unit-tests> 2026-06-28 12:22:00.424 UTC [55241] ERROR:  relation "goose_db_version" does not exist at character 361264niks3-go-unit-tests> 2026-06-28 12:22:00.424 UTC [55241] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1265niks3-go-unit-tests> 2026/06/28 12:22:00 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=01266niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=pending_closures1267niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=pending_objects1268niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=multipart_uploads1269niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=closures1270niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=objects1271niks3-go-unit-tests> === NAME  TestClientMultipleUploads1272niks3-go-unit-tests>     client_integration_test.go:338: Created store path 2: /nix/var/nix/builds/nix-54969-1347812711/TestClientMultipleUploads1343215351/001/store/q3zld15svch92var0npla3ggps8zpml3-test-file-2.txt1273niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (8.35ms)1274niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (444.71µs)1275niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (749.08µs)1276niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (2.56ms)1277niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001278niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (999.21µs)1279niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (308.63µs)1280niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21281niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Created nix-cache-info in bucket bucket=bucket351282niks3-go-unit-tests> === NAME  TestClientWithDependencies1283niks3-go-unit-tests>     client_integration_test.go:593: Built derivation: /nix/var/nix/builds/nix-54969-1347812711/TestClientWithDependencies1310339833/001/store/9i9riaj43a7v9hwg9ciyvn11hws346gp-test-script1284niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/api/pending_closures1285niks3-go-unit-tests>     client_integration_test.go:595: Found 1 dependencies (including self)1286niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1287niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading dpygddiz6h22k836mhrw4c8g8l7nrhd4-unpinned-file.txt (128B)1288niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1289niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Signed narinfos id=2 count=11290niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 1 narinfos1291niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1292niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Completed upload id=21293niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Upload complete. (89ms)1294niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received create pin request method=POST path=/api/pins/myapp1295niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Created/updated pin name=myapp store_path=/nix/var/nix/builds/nix-54969-1347812711/TestPinProtectsFromGC2077977858/001/store/39bh0l82p8qjk67hh8d48rsprinq6nzc-pinned-file.txt narinfo_key=39bh0l82p8qjk67hh8d48rsprinq6nzc.narinfo1296niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Starting cleanup of old closures method=DELETE path=/api/closures1297niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Garbage collection started1298niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Aborted multipart uploads count=01299niks3-go-unit-tests> 2026/06/28 12:22:00 WARN Force mode enabled - objects will be deleted immediately without grace period1300niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/api/pending_closures1301niks3-go-unit-tests> 2026-06-28 12:22:00.550 UTC [55266] ERROR:  relation "goose_db_version" does not exist at character 361302niks3-go-unit-tests> 2026-06-28 12:22:00.550 UTC [55266] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1303niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/api/pending_closures1304niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/api/pending_closures1305niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1306niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading q3zld15svch92var0npla3ggps8zpml3-test-file-2.txt (160B)1307niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 495n54ywzmv5knfym5yal6pgham4j2db-test-file-1.txt (160B)1308niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading hr1r330kjch511npgvf300rsxqnxrc3w-test-file-0.txt (160B)1309niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1310niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Signed narinfos id=1 count=11311niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1312niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Signed narinfos id=2 count=11313niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign1314niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Signed narinfos id=3 count=11315niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 3 narinfos1316niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/api/pending_closures1317niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1318niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Completed upload id=11319niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1320niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 9i9riaj43a7v9hwg9ciyvn11hws346gp-test-script (136B)1321niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1322niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Completed upload id=21323niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1324niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Completed upload id=31325niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Upload complete. (86ms)1326niks3-go-unit-tests> === NAME  TestClientMultipleUploads1327niks3-go-unit-tests>     client_integration_test.go:349: Uploaded 3 paths in 122.933542ms1328niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1329niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Signed narinfos id=1 count=11330niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 1 narinfos1331niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1332niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Completed upload id=11333niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Upload complete. (42ms)1334niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (8.83ms)1335niks3-go-unit-tests> === NAME  TestClientWithDependencies1336niks3-go-unit-tests>     client_integration_test.go:597: Skipping nix copy test - isolated store (/nix/var/nix/builds/nix-54969-1347812711/TestClientWithDependencies1310339833/001/store) requires matching store prefix1337niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (0.80s)1338niks3-go-unit-tests> === CONT  TestReadProxyRangeRequest1339niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (4.54ms)1340niks3-go-unit-tests> --- PASS: TestClientWithDependencies (0.85s)1341niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo1342niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_parent1343niks3-go-unit-tests> === CONT  TestIsValidCachePath/index.html1344niks3-go-unit-tests> === CONT  TestIsValidCachePath/nix-cache-info1345niks3-go-unit-tests> === CONT  TestIsValidCachePath/realisation1346niks3-go-unit-tests> === CONT  TestIsValidCachePath/log1347niks3-go-unit-tests> === CONT  TestIsValidCachePath/ls1348niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_uncompressed1349niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_bz21350niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_xz1351niks3-go-unit-tests> === CONT  TestIsValidCachePath/nar_zst1352niks3-go-unit-tests> === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars1353niks3-go-unit-tests> === CONT  TestIsValidCachePath/empty1354niks3-go-unit-tests> === CONT  TestIsValidCachePath/short_hash1355niks3-go-unit-tests> === CONT  TestIsValidCachePath/wrong_extension1356niks3-go-unit-tests> === CONT  TestIsValidCachePath/leading_slash1357niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_u1358niks3-go-unit-tests> === CONT  TestIsValidCachePath/random_path1359niks3-go-unit-tests> === CONT  TestIsValidCachePath/invalid_char_e1360niks3-go-unit-tests> === CONT  TestIsValidCachePath/traversal_in_middle1361niks3-go-unit-tests> --- PASS: TestIsValidCachePath (0.00s)1362niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo (0.00s)1363niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1364niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/index.html (0.00s)1365niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1366niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/realisation (0.00s)1367niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/log (0.00s)1368niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/ls (0.00s)1369niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1370niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1371niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_xz (0.00s)1372niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/nar_zst (0.00s)1373niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)1374niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/empty (0.00s)1375niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/short_hash (0.00s)1376niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1377niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/leading_slash (0.00s)1378niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1379niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/random_path (0.00s)1380niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1381niks3-go-unit-tests>     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1382niks3-go-unit-tests> === CONT  TestParseSingleRange/none1383niks3-go-unit-tests> === CONT  TestParseSingleRange/open-ended1384niks3-go-unit-tests> === CONT  TestParseSingleRange/start_far_past_EOF1385niks3-go-unit-tests> === CONT  TestParseSingleRange/start_past_EOF1386niks3-go-unit-tests> === CONT  TestParseSingleRange/single_byte1387niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix_exceeds_size1388niks3-go-unit-tests> === CONT  TestParseSingleRange/suffix1389niks3-go-unit-tests> === CONT  TestParseSingleRange/end_clamped_to_size1390niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_both_empty1391niks3-go-unit-tests> === CONT  TestParseSingleRange/closed1392niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_end_before_start1393niks3-go-unit-tests> === CONT  TestParseSingleRange/multi-range_ignored1394niks3-go-unit-tests> === CONT  TestParseSingleRange/malformed_no_dash1395niks3-go-unit-tests> === CONT  TestParseSingleRange/unknown_unit1396niks3-go-unit-tests> --- PASS: TestParseSingleRange (0.00s)1397niks3-go-unit-tests>     --- PASS: TestParseSingleRange/none (0.00s)1398niks3-go-unit-tests>     --- PASS: TestParseSingleRange/open-ended (0.00s)1399niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1400niks3-go-unit-tests>     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1401niks3-go-unit-tests>     --- PASS: TestParseSingleRange/single_byte (0.00s)1402niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1403niks3-go-unit-tests>     --- PASS: TestParseSingleRange/suffix (0.00s)1404niks3-go-unit-tests>     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1405niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1406niks3-go-unit-tests>     --- PASS: TestParseSingleRange/closed (0.00s)1407niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1408niks3-go-unit-tests>     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1409niks3-go-unit-tests>     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1410niks3-go-unit-tests>     --- PASS: TestParseSingleRange/unknown_unit (0.00s)1411niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1412niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/1413niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1414niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received complete multipart upload request method=POST path=/1415niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1416niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received request for more parts method=POST path=/1417niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1418niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/1419niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)1420niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1421niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1422niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1423niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1424niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure1425niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/1426niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (2.22ms)1427niks3-go-unit-tests> 2026-06-28 12:22:00.577 UTC [55269] ERROR:  relation "goose_db_version" does not exist at character 361428niks3-go-unit-tests> 2026-06-28 12:22:00.577 UTC [55269] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1429niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (9.02ms)1430niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001431niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (1.27ms)1432niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (293.38µs)1433niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21434niks3-go-unit-tests> 2026/06/28 12:22:00 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1435niks3-go-unit-tests> 2026/06/28 12:22:00 WARN mTLS auth: bound subjects configured but subject DN unavailable1436niks3-go-unit-tests> 2026/06/28 12:22:00 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1437niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.26s)1438niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart1439niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received complete multipart upload request method=POST path=/1440niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (12.94ms)1441niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (5.21ms)1442niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts1443niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received request for more parts method=POST path=/1444niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (9.95ms)1445niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (8.31ms)1446niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001447niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo1448niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation_plus_in_output1449niks3-go-unit-tests> === CONT  TestIsValidUploadKey/unknown_type1450niks3-go-unit-tests> === CONT  TestIsValidUploadKey/empty_key1451niks3-go-unit-tests> === CONT  TestIsValidUploadKey/absolute1452niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal_nar1453niks3-go-unit-tests> === CONT  TestIsValidUploadKey/traversal1454niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing_key,_narinfo_type1455niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_key,_narinfo_type1456niks3-go-unit-tests> === CONT  TestIsValidUploadKey/narinfo_key,_nar_type1457niks3-go-unit-tests> === CONT  TestIsValidUploadKey/index.html1458niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nix-cache-info1459niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_home-manager_file1460niks3-go-unit-tests> === CONT  TestIsValidUploadKey/realisation1461niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_equals1462niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_question_mark1463niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_plain1464niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log_plus_in_name1465niks3-go-unit-tests> === CONT  TestIsValidUploadKey/build_log1466niks3-go-unit-tests> === CONT  TestIsValidUploadKey/listing1467niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_xz1468niks3-go-unit-tests> === CONT  TestIsValidUploadKey/nar_zst1469niks3-go-unit-tests> --- PASS: TestIsValidUploadKey (0.00s)1470niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo (0.00s)1471niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1472niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1473niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/empty_key (0.00s)1474niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/absolute (0.00s)1475niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1476niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/traversal (0.00s)1477niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1478niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1479niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1480niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/index.html (0.00s)1481niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1482niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1483niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/realisation (0.00s)1484niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1485niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1486niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1487niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1488niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/build_log (0.00s)1489niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/listing (0.00s)1490niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1491niks3-go-unit-tests>     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1492niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/narinfo1493niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/10_GiB_nar1494niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/unknown_size1495niks3-go-unit-tests> === CONT  TestProxyWriteTimeout/1_GiB_nar1496niks3-go-unit-tests> --- PASS: TestProxyWriteTimeout (0.00s)1497niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1498niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1499niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1500niks3-go-unit-tests>     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)1501niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidStorePath1502niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (1.97ms)1503niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (443.71µs)1504niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21505niks3-go-unit-tests> 2026/06/28 12:22:00 WARN mTLS auth: subject not in bound subjects subject="CN=writer"1506niks3-go-unit-tests> --- PASS: TestService_ReadAuthMiddleware (0.27s)1507niks3-go-unit-tests> === CONT  TestClientErrorHandling/ServerNotAvailable1508niks3-go-unit-tests> 2026-06-28 12:22:00.629 UTC [55272] ERROR:  relation "goose_db_version" does not exist at character 361509niks3-go-unit-tests> 2026-06-28 12:22:00.629 UTC [55272] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1510niks3-go-unit-tests> 2026-06-28 12:22:00.648 UTC [55275] ERROR:  relation "goose_db_version" does not exist at character 361511niks3-go-unit-tests> 2026-06-28 12:22:00.648 UTC [55275] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1512niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (17.03ms)1513niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (751.21µs)1514niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (1.17ms)1515niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (2.44ms)1516niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001517niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (2.25ms)1518niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (323.5µs)1519niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21520niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/api/pending_closures1521niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (31.86ms)1522niks3-go-unit-tests> === NAME  TestClientCADerivations1523niks3-go-unit-tests>     client_ca_test.go:136: Built CA derivation: /nix/var/nix/builds/nix-54969-1347812711/TestClientCADerivations3512084064/001/store/n3y38cvggn1z8qz413pwlckafm53g5iq-ca-test1524niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (1.41ms)1525niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/api/pending_closures1526niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (3.95ms)1527niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (2.32ms)1528niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001529niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (1.77ms)1530niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (410µs)1531niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21532niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.28s)1533niks3-go-unit-tests> === CONT  TestClientErrorHandling/InvalidAuthToken1534niks3-go-unit-tests> === NAME  TestClientCADerivations1535niks3-go-unit-tests>     client_ca_test.go:139: Found 1 dependencies (including self)1536niks3-go-unit-tests> 2026-06-28 12:22:00.761 UTC [55286] ERROR:  relation "goose_db_version" does not exist at character 361537niks3-go-unit-tests> 2026-06-28 12:22:00.761 UTC [55286] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1538niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (3.62ms)1539niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (744.63µs)1540niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (1.1ms)1541niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (4.04ms)1542niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001543niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (2.16ms)1544niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (1.01ms)1545niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21546niks3-go-unit-tests> --- PASS: TestReadProxyRangeRequest (0.22s)1547niks3-go-unit-tests> === CONT  TestCacheConfigHandler/full_config,_no_issuer1548niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_signing_keys1549niks3-go-unit-tests> === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1550niks3-go-unit-tests> === CONT  TestCacheConfigHandler/no_cache_url_configured1551niks3-go-unit-tests> --- PASS: TestCacheConfigHandler (0.00s)1552niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)1553niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)1554niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)1555niks3-go-unit-tests>     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)1556niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token1557niks3-go-unit-tests> 2026/06/28 12:22:00 INFO OIDC auth successful provider=test1558niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected1559niks3-go-unit-tests> 2026/06/28 12:22:00 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]1560niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1561niks3-go-unit-tests> === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1562niks3-go-unit-tests> 2026/06/28 12:22:00 WARN Authentication failed token_preview=eyJhbGciOi...nMKlYaFAqg 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]1563niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware_OIDC (0.43s)1564niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1565niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1566niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1567niks3-go-unit-tests>     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1568niks3-go-unit-tests> 2026-06-28 12:22:00.817 UTC [55294] ERROR:  relation "goose_db_version" does not exist at character 361569niks3-go-unit-tests> 2026-06-28 12:22:00.817 UTC [55294] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1570niks3-go-unit-tests> 2026/06/28 12:22:00 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1571niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (13.2ms)1572niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (799.96µs)1573niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (1.69ms)1574niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1575niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (2.16ms)1576niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001577niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (933.38µs)1578niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (324.92µs)1579niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21580niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=YWM1ZThmMTItMjg0Mi00YTUyLThiYzItOTg3ZWEwMDI2MDUwLmE5ZjhhMTI1LWFlMzAtNGNmMy05YTk1LTRkOWI0NGIzNTJiNXgxNzgyNjQ5MzIwNjkxMzc5MDAw parts=121581niks3-go-unit-tests> --- PASS: TestRedundantMultipartUpload (0.46s)1582niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received uploads request method=POST path=/api/pending_closures1583niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1584niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading n3y38cvggn1z8qz413pwlckafm53g5iq-ca-test (144B)1585niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1586niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Signed narinfos id=1 count=11587niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Uploading 1 narinfos1588niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1589niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Completed upload id=11590niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Upload complete. (81ms)1591niks3-go-unit-tests> === NAME  TestClientCADerivations1592niks3-go-unit-tests>     client_ca_test.go:180: Narinfo contains CA field: StorePath: /nix/var/nix/builds/nix-54969-1347812711/TestClientCADerivations3512084064/001/store/n3y38cvggn1z8qz413pwlckafm53g5iq-ca-test1593niks3-go-unit-tests>         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1594niks3-go-unit-tests>         Compression: zstd1595niks3-go-unit-tests>         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1596niks3-go-unit-tests>         NarSize: 1441597niks3-go-unit-tests>         References: 1598niks3-go-unit-tests>         Deriver: /nix/var/nix/builds/nix-54969-1347812711/TestClientCADerivations3512084064/001/store/5n51z92fi05iwfjkfsy3nyd5nqgjxq51-ca-test.drv1599niks3-go-unit-tests>         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1600niks3-go-unit-tests>     client_ca_test.go:185: Checking for realisation files in S3...1601niks3-go-unit-tests>     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1602niks3-go-unit-tests>     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1603niks3-go-unit-tests> --- PASS: TestUploadHandlersRejectOversizedBody (0.02s)1604niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.02s)1605niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.02s)1606niks3-go-unit-tests>     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.29s)1607niks3-go-unit-tests> 2026-06-28 12:22:00.878 UTC [55299] ERROR:  relation "goose_db_version" does not exist at character 361608niks3-go-unit-tests> 2026-06-28 12:22:00.878 UTC [55299] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1609niks3-go-unit-tests> 2026/06/28 12:22:00 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=01610niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=pending_closures1611niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=pending_objects1612niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=multipart_uploads1613niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=closures1614niks3-go-unit-tests> 2026/06/28 12:22:00 INFO Vacuumed table table=objects1615niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20241026095416_initial_model.sql (3.51ms)1616niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251210153512_drop_unused_gin_index.sql (387.88µs)1617niks3-go-unit-tests> === NAME  TestClientCADerivations1618niks3-go-unit-tests>     client_ca_test.go:258: nix copy output: error: binary cache 's3://bucket35?endpoint=http://localhost:51963&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/nix/var/nix/builds/nix-54969-1347812711/TestClientCADerivations3512084064/001/store'1619niks3-go-unit-tests>     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11620niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20251218171726_add_pins.sql (707.79µs)1621niks3-go-unit-tests> 2026/06/28 12:22:00 OK   20260628120000_add_object_size_and_stats.sql (930.79µs)1622niks3-go-unit-tests> 2026/06/28 12:22:00 goose: successfully migrated database to version: 202606281200001623niks3-go-unit-tests> --- PASS: TestClientCADerivations (0.80s)1624niks3-go-unit-tests> 2026/06/28 12:22:00 OK   1_commit_pending_closure.sql (905.46µs)1625niks3-go-unit-tests> 2026/06/28 12:22:00 OK   2_object_stats_trigger.sql (204.67µs)1626niks3-go-unit-tests> 2026/06/28 12:22:00 goose: up to current file version: 21627niks3-go-unit-tests> 2026/06/28 12:22:00 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=202.550609ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1628niks3-go-unit-tests> 2026/06/28 12:22:01 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1629niks3-go-unit-tests> 2026/06/28 12:22:01 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=389.108454ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1630niks3-go-unit-tests> 2026/06/28 12:22:01 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=807.271007ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1631niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01632niks3-go-unit-tests> === NAME  TestClientIntegration1633niks3-go-unit-tests>     client_integration_test.go:303: Objects in database after GC:1634niks3-go-unit-tests>     client_integration_test.go:303: Successfully deleted all objects with GC --force1635niks3-go-unit-tests> --- PASS: TestClientIntegration (2.73s)1636niks3-go-unit-tests> 2026/06/28 12:22:02 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.492717181s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp 127.0.0.1:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures1637niks3-go-unit-tests> 2026/06/28 12:22:02 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=01638niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1639niks3-go-unit-tests>     client_integration_test.go:709: Pin successfully protected closure from garbage collection1640niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (2.86s)1641niks3-go-unit-tests> --- PASS: TestClientErrorHandling (0.00s)1642niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidStorePath (0.25s)1643niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.33s)1644niks3-go-unit-tests>     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.21s)1645niks3-go-unit-tests> 2026/06/28 12:22:05 WARN Rate limiter enabled after throttle name=s3-test rate=51646niks3-go-unit-tests> 2026/06/28 12:22:05 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1647niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1648niks3-go-unit-tests>     throttle_test.go:226: Proxy stats: total=15, throttled=10, completeMultipart=101649niks3-go-unit-tests>     throttle_test.go:228: Rate limiter: enabled=true, rate=5.001650niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (6.29s)1651niks3-go-unit-tests> PASS1652niks3-go-unit-tests> {"timestamp":"2026-06-28T12:22:06.152472Z","level":"ERROR","fields":{"message":"Unknown connection IO error:Cancelled","peer_addr":"127.0.0.1:52028"},"target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":973,"threadName":"rustfs-worker","threadId":"ThreadId(10)"}1653niks3-go-unit-tests> 2026-06-28 12:22:06.214 UTC [55004] LOG:  received smart shutdown request1654niks3-go-unit-tests> 2026-06-28 12:22:06.215 UTC [55004] LOG:  background worker "logical replication launcher" (PID 55011) exited with exit code 11655niks3-go-unit-tests> 2026-06-28 12:22:06.224 UTC [55006] LOG:  shutting down1656niks3-go-unit-tests> 2026-06-28 12:22:06.224 UTC [55006] LOG:  checkpoint starting: shutdown immediate1657niks3-go-unit-tests> 2026-06-28 12:22:07.185 UTC [55006] LOG:  checkpoint complete: wrote 12694 buffers (77.5%); 0 WAL file(s) added, 0 removed, 11 recycled; write=0.708 s, sync=0.233 s, total=0.962 s; sync files=13767, longest=0.001 s, average=0.001 s; distance=188551 kB, estimate=188551 kB; lsn=0/CD348A0, redo lsn=0/CD348A01658niks3-go-unit-tests> 2026-06-28 12:22:07.188 UTC [55004] LOG:  database system is shut down1659niks3-go-unit-tests> Running OIDC tests...1660niks3-go-unit-tests> === RUN   TestGlobMatch1661niks3-go-unit-tests> === PAUSE TestGlobMatch1662niks3-go-unit-tests> === RUN   TestAudienceForIssuer1663niks3-go-unit-tests> === PAUSE TestAudienceForIssuer1664niks3-go-unit-tests> === RUN   TestValidateToken_ValidToken1665niks3-go-unit-tests> === PAUSE TestValidateToken_ValidToken1666niks3-go-unit-tests> === RUN   TestValidateToken_WrongAudience1667niks3-go-unit-tests> === PAUSE TestValidateToken_WrongAudience1668niks3-go-unit-tests> === RUN   TestValidateToken_Expired1669niks3-go-unit-tests> === PAUSE TestValidateToken_Expired1670niks3-go-unit-tests> === RUN   TestValidateToken_BoundClaimsMismatch1671niks3-go-unit-tests> === PAUSE TestValidateToken_BoundClaimsMismatch1672niks3-go-unit-tests> === RUN   TestValidateToken_BoundSubjectMismatch1673niks3-go-unit-tests> === PAUSE TestValidateToken_BoundSubjectMismatch1674niks3-go-unit-tests> === RUN   TestValidateToken_MultipleProviders1675niks3-go-unit-tests> === PAUSE TestValidateToken_MultipleProviders1676niks3-go-unit-tests> === RUN   TestValidateToken_NoMatchingProvider1677niks3-go-unit-tests> === PAUSE TestValidateToken_NoMatchingProvider1678niks3-go-unit-tests> === CONT  TestGlobMatch1679niks3-go-unit-tests> === RUN   TestGlobMatch/foo_foo1680niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_foo1681niks3-go-unit-tests> === CONT  TestValidateToken_WrongAudience1682niks3-go-unit-tests> === CONT  TestValidateToken_BoundClaimsMismatch1683niks3-go-unit-tests> === CONT  TestValidateToken_ValidToken1684niks3-go-unit-tests> === RUN   TestGlobMatch/foo_bar1685niks3-go-unit-tests> === PAUSE TestGlobMatch/foo_bar1686niks3-go-unit-tests> === RUN   TestGlobMatch/*_1687niks3-go-unit-tests> === PAUSE TestGlobMatch/*_1688niks3-go-unit-tests> === RUN   TestGlobMatch/*_anything1689niks3-go-unit-tests> === PAUSE TestGlobMatch/*_anything1690niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foo1691niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foo1692niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_foobar1693niks3-go-unit-tests> === CONT  TestAudienceForIssuer1694niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_foobar1695niks3-go-unit-tests> === RUN   TestGlobMatch/foo*_bar1696niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*_bar1697niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_bar1698niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_bar1699niks3-go-unit-tests> --- PASS: TestAudienceForIssuer (0.00s)1700niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foobar1701niks3-go-unit-tests> === CONT  TestValidateToken_MultipleProviders1702niks3-go-unit-tests> === CONT  TestValidateToken_NoMatchingProvider1703niks3-go-unit-tests> === CONT  TestValidateToken_BoundSubjectMismatch1704niks3-go-unit-tests> === CONT  TestValidateToken_Expired1705niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foobar1706niks3-go-unit-tests> === RUN   TestGlobMatch/*bar_foo1707niks3-go-unit-tests> === PAUSE TestGlobMatch/*bar_foo1708niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobar1709niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobar1710niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foo123bar1711niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foo123bar1712niks3-go-unit-tests> === RUN   TestGlobMatch/foo*bar_foobarbaz1713niks3-go-unit-tests> === PAUSE TestGlobMatch/foo*bar_foobarbaz1714niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo/bar1715niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo/bar1716niks3-go-unit-tests> === RUN   TestGlobMatch/*/*_foo1717niks3-go-unit-tests> === PAUSE TestGlobMatch/*/*_foo1718niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/heads/main1719niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main1720niks3-go-unit-tests> === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.01721niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.01722niks3-go-unit-tests> === RUN   TestGlobMatch/refs/*/main_refs/heads/main1723niks3-go-unit-tests> === PAUSE TestGlobMatch/refs/*/main_refs/heads/main1724niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_foo1725niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_foo1726niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fo1727niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fo1728niks3-go-unit-tests> === RUN   TestGlobMatch/fo?_fooo1729niks3-go-unit-tests> === PAUSE TestGlobMatch/fo?_fooo1730niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_foo1731niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_foo1732niks3-go-unit-tests> === RUN   TestGlobMatch/?oo_boo1733niks3-go-unit-tests> === PAUSE TestGlobMatch/?oo_boo1734niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1735niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1736niks3-go-unit-tests> === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1737niks3-go-unit-tests> === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1738niks3-go-unit-tests> === CONT  TestGlobMatch/foo_foo1739niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main1740niks3-go-unit-tests> === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main1741niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_boo1742niks3-go-unit-tests> === CONT  TestGlobMatch/?oo_foo1743niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fooo1744niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_fo1745niks3-go-unit-tests> === CONT  TestGlobMatch/fo?_foo1746niks3-go-unit-tests> === CONT  TestGlobMatch/refs/*/main_refs/heads/main1747niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.01748niks3-go-unit-tests> === CONT  TestGlobMatch/refs/heads/*_refs/heads/main1749niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo1750niks3-go-unit-tests> === CONT  TestGlobMatch/*/*_foo/bar1751niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobarbaz1752niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foo123bar1753niks3-go-unit-tests> === CONT  TestGlobMatch/foo*bar_foobar1754niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foo1755niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_foobar1756niks3-go-unit-tests> === CONT  TestGlobMatch/*bar_bar1757niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_bar1758niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foobar1759niks3-go-unit-tests> === CONT  TestGlobMatch/foo*_foo1760niks3-go-unit-tests> === CONT  TestGlobMatch/*_anything1761niks3-go-unit-tests> === CONT  TestGlobMatch/*_1762niks3-go-unit-tests> === CONT  TestGlobMatch/foo_bar1763niks3-go-unit-tests> --- PASS: TestGlobMatch (0.00s)1764niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_foo (0.00s)1765niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)1766niks3-go-unit-tests>     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)1767niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_boo (0.00s)1768niks3-go-unit-tests>     --- PASS: TestGlobMatch/?oo_foo (0.00s)1769niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fooo (0.00s)1770niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_fo (0.00s)1771niks3-go-unit-tests>     --- PASS: TestGlobMatch/fo?_foo (0.00s)1772niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)1773niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)1774niks3-go-unit-tests>     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)1775niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo (0.00s)1776niks3-go-unit-tests>     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)1777niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)1778niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)1779niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)1780niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foo (0.00s)1781niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_foobar (0.00s)1782niks3-go-unit-tests>     --- PASS: TestGlobMatch/*bar_bar (0.00s)1783niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_bar (0.00s)1784niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foobar (0.00s)1785niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo*_foo (0.00s)1786niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_anything (0.00s)1787niks3-go-unit-tests>     --- PASS: TestGlobMatch/*_ (0.00s)1788niks3-go-unit-tests>     --- PASS: TestGlobMatch/foo_bar (0.00s)1789niks3-go-unit-tests> 2026/06/28 12:22:07 INFO OIDC provider initialized name=test1790niks3-go-unit-tests> 2026/06/28 12:22:07 INFO OIDC provider initialized name=provider11791niks3-go-unit-tests> 2026/06/28 12:22:07 INFO OIDC provider initialized name=test1792niks3-go-unit-tests> 2026/06/28 12:22:07 INFO OIDC provider initialized name=provider11793niks3-go-unit-tests> 2026/06/28 12:22:07 INFO OIDC provider initialized name=test1794niks3-go-unit-tests> 2026/06/28 12:22:07 INFO OIDC provider initialized name=test1795niks3-go-unit-tests> 2026/06/28 12:22:07 INFO OIDC provider initialized name=test1796niks3-go-unit-tests> 2026/06/28 12:22:07 INFO OIDC provider initialized name=provider21797niks3-go-unit-tests> --- PASS: TestValidateToken_NoMatchingProvider (0.01s)1798niks3-go-unit-tests> --- PASS: TestValidateToken_WrongAudience (0.01s)1799niks3-go-unit-tests> --- PASS: TestValidateToken_BoundClaimsMismatch (0.01s)1800niks3-go-unit-tests> --- PASS: TestValidateToken_BoundSubjectMismatch (0.01s)1801niks3-go-unit-tests> --- PASS: TestValidateToken_MultipleProviders (0.01s)1802niks3-go-unit-tests> --- PASS: TestValidateToken_Expired (0.01s)1803niks3-go-unit-tests> --- PASS: TestValidateToken_ValidToken (0.01s)1804niks3-go-unit-tests> PASS1805niks3-go-unit-tests> Running hook tests...1806niks3-go-unit-tests> === RUN   TestSendPathsEmpty1807niks3-go-unit-tests> === PAUSE TestSendPathsEmpty1808niks3-go-unit-tests> === RUN   TestQueueEnqueueAndFetch1809niks3-go-unit-tests> === PAUSE TestQueueEnqueueAndFetch1810niks3-go-unit-tests> === RUN   TestQueueDeduplication1811niks3-go-unit-tests> === PAUSE TestQueueDeduplication1812niks3-go-unit-tests> === RUN   TestQueueRemove1813niks3-go-unit-tests> === PAUSE TestQueueRemove1814niks3-go-unit-tests> === RUN   TestQueueFetchBatchLimit1815niks3-go-unit-tests> === PAUSE TestQueueFetchBatchLimit1816niks3-go-unit-tests> === RUN   TestQueueFetchRemoveLifecycle1817niks3-go-unit-tests> === PAUSE TestQueueFetchRemoveLifecycle1818niks3-go-unit-tests> === RUN   TestQueueConcurrentWriters1819niks3-go-unit-tests> === PAUSE TestQueueConcurrentWriters1820niks3-go-unit-tests> === RUN   TestServerClientIntegration1821niks3-go-unit-tests> === PAUSE TestServerClientIntegration1822niks3-go-unit-tests> === RUN   TestServerQueueError1823niks3-go-unit-tests> === PAUSE TestServerQueueError1824niks3-go-unit-tests> === RUN   TestGetListenerSocketActivation1825niks3-go-unit-tests>     server_test.go:210: === RUN   TestGetListenerSocketActivation1826niks3-go-unit-tests>         --- PASS: TestGetListenerSocketActivation (0.00s)1827niks3-go-unit-tests>         PASS1828niks3-go-unit-tests>         1829niks3-go-unit-tests> --- PASS: TestGetListenerSocketActivation (0.01s)1830niks3-go-unit-tests> === RUN   TestWorkerUploadsAndRemoves1831niks3-go-unit-tests> === PAUSE TestWorkerUploadsAndRemoves1832niks3-go-unit-tests> === RUN   TestWorkerSkipsGCdPaths1833niks3-go-unit-tests> === PAUSE TestWorkerSkipsGCdPaths1834niks3-go-unit-tests> === RUN   TestWorkerPrunesClosureDeps1835niks3-go-unit-tests> === PAUSE TestWorkerPrunesClosureDeps1836niks3-go-unit-tests> === CONT  TestSendPathsEmpty1837niks3-go-unit-tests> --- PASS: TestSendPathsEmpty (0.00s)1838niks3-go-unit-tests> === CONT  TestQueueFetchRemoveLifecycle1839niks3-go-unit-tests> === CONT  TestQueueConcurrentWriters1840niks3-go-unit-tests> === CONT  TestServerQueueError1841niks3-go-unit-tests> === CONT  TestWorkerUploadsAndRemoves1842niks3-go-unit-tests> === CONT  TestQueueRemove1843niks3-go-unit-tests> === CONT  TestWorkerPrunesClosureDeps1844niks3-go-unit-tests> === CONT  TestWorkerSkipsGCdPaths1845niks3-go-unit-tests> === CONT  TestQueueDeduplication1846niks3-go-unit-tests> === CONT  TestQueueFetchBatchLimit1847niks3-go-unit-tests> === CONT  TestQueueEnqueueAndFetch1848niks3-go-unit-tests> 2026/06/28 12:22:08 ERROR Failed to queue paths error="permission denied" count=11849niks3-go-unit-tests> --- PASS: TestServerQueueError (0.00s)1850niks3-go-unit-tests> === CONT  TestServerClientIntegration1851niks3-go-unit-tests> --- PASS: TestServerClientIntegration (0.00s)1852niks3-go-unit-tests> 2026/06/28 12:22:08 INFO Upload queue status pending=21853niks3-go-unit-tests> 2026/06/28 12:22:08 WARN Store path no longer exists (garbage collected?), removing from queue path=/nix/var/nix/builds/nix-54969-1347812711/TestWorkerSkipsGCdPaths873094217/002/nonexistent1854niks3-go-unit-tests> 2026/06/28 12:22:08 INFO Uploading batch count=11855niks3-go-unit-tests> 2026/06/28 12:22:08 INFO Upload queue status pending=21856niks3-go-unit-tests> 2026/06/28 12:22:08 INFO Uploading batch count=21857niks3-go-unit-tests> --- PASS: TestQueueDeduplication (0.01s)1858niks3-go-unit-tests> --- PASS: TestQueueRemove (0.01s)1859niks3-go-unit-tests> --- PASS: TestQueueEnqueueAndFetch (0.01s)1860niks3-go-unit-tests> 2026/06/28 12:22:08 INFO Upload queue status pending=21861niks3-go-unit-tests> 2026/06/28 12:22:08 INFO Uploading batch count=11862niks3-go-unit-tests> --- PASS: TestQueueFetchRemoveLifecycle (0.01s)1863niks3-go-unit-tests> --- PASS: TestQueueFetchBatchLimit (0.01s)1864niks3-go-unit-tests> --- PASS: TestWorkerSkipsGCdPaths (0.06s)1865niks3-go-unit-tests> --- PASS: TestWorkerUploadsAndRemoves (0.06s)1866niks3-go-unit-tests> --- PASS: TestWorkerPrunesClosureDeps (0.06s)1867niks3-go-unit-tests> --- PASS: TestQueueConcurrentWriters (0.16s)1868niks3-go-unit-tests> PASS18691870post-build step Upload coverage to codecov: ok1871Skipping codecov: project=Mic92/niks3 attr=aarch64-darwin.go-unit-tests