nixbot

1this derivation will be built:2  /nix/store/wqh40hccrrv2rhg3sj6fc48ssa1w4rv8-niks3-go-unit-tests.drv3building '/nix/store/wqh40hccrrv2rhg3sj6fc48ssa1w4rv8-niks3-go-unit-tests.drv' on 'ssh-ng://customer@mac02'4building '/nix/store/wqh40hccrrv2rhg3sj6fc48ssa1w4rv8-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   TestRegisterUploadedObjectReusesConnections9niks3-go-unit-tests> === PAUSE TestRegisterUploadedObjectReusesConnections10niks3-go-unit-tests> === RUN   TestCaseHackSuffix11niks3-go-unit-tests> === PAUSE TestCaseHackSuffix12niks3-go-unit-tests> === RUN   TestFilterOversizedClosures13niks3-go-unit-tests> === PAUSE TestFilterOversizedClosures14niks3-go-unit-tests> === RUN   TestUploadMultipart_PartsInParallel15niks3-go-unit-tests> === PAUSE TestUploadMultipart_PartsInParallel16niks3-go-unit-tests> === RUN   TestPartSizeForNAR17niks3-go-unit-tests> === PAUSE TestPartSizeForNAR18niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer19niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer20niks3-go-unit-tests> === RUN   TestDumpPathCaseHackMatchesNix21niks3-go-unit-tests> --- PASS: TestDumpPathCaseHackMatchesNix (0.07s)22niks3-go-unit-tests> === RUN   TestDumpPathCaseHackCollision23niks3-go-unit-tests> --- PASS: TestDumpPathCaseHackCollision (0.00s)24niks3-go-unit-tests> === RUN   TestDumpPathMatchesNix25niks3-go-unit-tests> === PAUSE TestDumpPathMatchesNix26niks3-go-unit-tests> === RUN   TestDumpPathSingleFile27niks3-go-unit-tests> === PAUSE TestDumpPathSingleFile28niks3-go-unit-tests> === RUN   TestDumpPathWriterError29niks3-go-unit-tests> === PAUSE TestDumpPathWriterError30niks3-go-unit-tests> === RUN   TestEncodeNixBase3231niks3-go-unit-tests> === PAUSE TestEncodeNixBase3232niks3-go-unit-tests> === RUN   TestEncodeNixBase32WithRealHash33niks3-go-unit-tests> === PAUSE TestEncodeNixBase32WithRealHash34niks3-go-unit-tests> === RUN   TestConvertHashToNix3235niks3-go-unit-tests> === PAUSE TestConvertHashToNix3236niks3-go-unit-tests> === RUN   TestGetStorePathHash37niks3-go-unit-tests> === PAUSE TestGetStorePathHash38niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility39niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility40niks3-go-unit-tests> === RUN   TestParsePathInfoJSON41niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON42niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths43niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths44niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility45niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility46niks3-go-unit-tests> === RUN   TestRateLimiterFeedback47niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback48niks3-go-unit-tests> === RUN   TestRateLimiterFeedback_400DoesNotCountAsSuccess49niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess50niks3-go-unit-tests> === RUN   TestResolveStorePath51niks3-go-unit-tests> === PAUSE TestResolveStorePath52niks3-go-unit-tests> === RUN   TestDoWithRetry_BodyReplayedViaGetBody53niks3-go-unit-tests> === PAUSE TestDoWithRetry_BodyReplayedViaGetBody54niks3-go-unit-tests> === RUN   TestShellSplit55niks3-go-unit-tests> === PAUSE TestShellSplit56niks3-go-unit-tests> === RUN   TestShellSplitErrors57niks3-go-unit-tests> === PAUSE TestShellSplitErrors58niks3-go-unit-tests> === RUN   TestStreamPushReportsEveryPath59niks3-go-unit-tests> === PAUSE TestStreamPushReportsEveryPath60niks3-go-unit-tests> === RUN   TestStreamPushBatchesUnderLoad61niks3-go-unit-tests> === PAUSE TestStreamPushBatchesUnderLoad62niks3-go-unit-tests> === RUN   TestStreamPushIsolatesFailures63niks3-go-unit-tests> === PAUSE TestStreamPushIsolatesFailures64niks3-go-unit-tests> === RUN   TestStreamPushGivesUpOnDeadServer65niks3-go-unit-tests> === PAUSE TestStreamPushGivesUpOnDeadServer66niks3-go-unit-tests> === RUN   TestStreamPushRequestLine67niks3-go-unit-tests> === PAUSE TestStreamPushRequestLine68niks3-go-unit-tests> === RUN   TestStreamPushReportsSignatures69niks3-go-unit-tests> === PAUSE TestStreamPushReportsSignatures70niks3-go-unit-tests> === RUN   TestClientSignaturesByStorePath71niks3-go-unit-tests> === PAUSE TestClientSignaturesByStorePath72niks3-go-unit-tests> === RUN   TestSetClientTLS73niks3-go-unit-tests> === PAUSE TestSetClientTLS74niks3-go-unit-tests> === RUN   TestSetClientTLSDoesNotMutateDefaultTransport75niks3-go-unit-tests> === PAUSE TestSetClientTLSDoesNotMutateDefaultTransport76niks3-go-unit-tests> === RUN   TestSetClientTLSErrors77niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors78niks3-go-unit-tests> === RUN   TestStaticToken79niks3-go-unit-tests> === PAUSE TestStaticToken80niks3-go-unit-tests> === RUN   TestFileTokenReadsAndCaches81niks3-go-unit-tests> === PAUSE TestFileTokenReadsAndCaches82niks3-go-unit-tests> === RUN   TestFileTokenMissing83niks3-go-unit-tests> === PAUSE TestFileTokenMissing84niks3-go-unit-tests> === RUN   TestFileTokenEmpty85niks3-go-unit-tests> === PAUSE TestFileTokenEmpty86niks3-go-unit-tests> === RUN   TestScriptTokenNoExpiryRerunsEveryCall87niks3-go-unit-tests> === PAUSE TestScriptTokenNoExpiryRerunsEveryCall88niks3-go-unit-tests> === RUN   TestScriptTokenCachesUntilRefresh89niks3-go-unit-tests> === PAUSE TestScriptTokenCachesUntilRefresh90niks3-go-unit-tests> === RUN   TestScriptTokenEmptyToken91niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyToken92niks3-go-unit-tests> === RUN   TestScriptTokenBadJSON93niks3-go-unit-tests> === PAUSE TestScriptTokenBadJSON94niks3-go-unit-tests> === RUN   TestScriptTokenScriptFails95niks3-go-unit-tests> === PAUSE TestScriptTokenScriptFails96niks3-go-unit-tests> === RUN   TestScriptTokenEmptyCommand97niks3-go-unit-tests> === PAUSE TestScriptTokenEmptyCommand98niks3-go-unit-tests> === CONT  TestDoServerRequestAttachesToken99niks3-go-unit-tests> === CONT  TestShellSplit100niks3-go-unit-tests> === CONT  TestSetClientTLSErrors101niks3-go-unit-tests> --- PASS: TestShellSplit (0.00s)102niks3-go-unit-tests> === CONT  TestSetClientTLSDoesNotMutateDefaultTransport103niks3-go-unit-tests> === CONT  TestScriptTokenCachesUntilRefresh104niks3-go-unit-tests> === CONT  TestStreamPushGivesUpOnDeadServer105niks3-go-unit-tests> === CONT  TestDoWithRetry_BodyReplayedViaGetBody106niks3-go-unit-tests> === CONT  TestScriptTokenNoExpiryRerunsEveryCall107niks3-go-unit-tests> === CONT  TestSetClientTLS108niks3-go-unit-tests> === CONT  TestResolveStorePath109niks3-go-unit-tests> === CONT  TestFileTokenMissing110niks3-go-unit-tests> 2026/10/01 10:24:40 ERROR Upload failed error="connection refused" count=20111niks3-go-unit-tests> 2026/10/01 10:24:40 ERROR Server seems unavailable, giving up on batch untried=17112niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Rate limiter enabled after throttle name=server-test rate=5113niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:61346114niks3-go-unit-tests> --- PASS: TestDoServerRequestAttachesToken (0.00s)115niks3-go-unit-tests> === CONT  TestStreamPushBatchesUnderLoad116niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Rate limiter backed off name=server-test rate=5117niks3-go-unit-tests> --- PASS: TestStreamPushGivesUpOnDeadServer (0.00s)118niks3-go-unit-tests> === CONT  TestStreamPushIsolatesFailures119niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:61346120niks3-go-unit-tests> 2026/10/01 10:24:40 ERROR Upload failed error="bad path" count=3121niks3-go-unit-tests> --- PASS: TestStreamPushIsolatesFailures (0.00s)122niks3-go-unit-tests> === CONT  TestScriptTokenEmptyCommand123niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyCommand (0.00s)124niks3-go-unit-tests> === CONT  TestScriptTokenScriptFails125niks3-go-unit-tests> --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.00s)126niks3-go-unit-tests> === CONT  TestScriptTokenBadJSON127niks3-go-unit-tests> --- PASS: TestScriptTokenScriptFails (0.00s)128niks3-go-unit-tests> === CONT  TestScriptTokenEmptyToken129niks3-go-unit-tests> --- PASS: TestFileTokenMissing (0.01s)130niks3-go-unit-tests> === CONT  TestFileTokenReadsAndCaches131niks3-go-unit-tests> --- PASS: TestResolveStorePath (0.01s)132niks3-go-unit-tests> === CONT  TestFileTokenEmpty133niks3-go-unit-tests> --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)134niks3-go-unit-tests> === CONT  TestStreamPushReportsEveryPath135niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_cert_file136niks3-go-unit-tests> --- PASS: TestStreamPushReportsEveryPath (0.00s)137niks3-go-unit-tests> --- PASS: TestFileTokenEmpty (0.00s)138niks3-go-unit-tests> === CONT  TestStaticToken139niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_cert_file140niks3-go-unit-tests> --- PASS: TestStaticToken (0.00s)141niks3-go-unit-tests> === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess142niks3-go-unit-tests> === CONT  TestShellSplitErrors143niks3-go-unit-tests> --- PASS: TestShellSplitErrors (0.00s)144niks3-go-unit-tests> === CONT  TestRateLimiterFeedback145niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_key_file146niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/429_enables_limiter147niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/429_enables_limiter148niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/503_enables_limiter149niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/503_enables_limiter150niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter151niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter152niks3-go-unit-tests> === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter153niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_key_file154niks3-go-unit-tests> === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter155niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Rate limiter enabled after throttle name=server-test rate=5156niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/missing_ca_file157niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/missing_ca_file158niks3-go-unit-tests> === RUN   TestSetClientTLSErrors/invalid_ca_file159niks3-go-unit-tests> === PAUSE TestSetClientTLSErrors/invalid_ca_file160niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility161niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/null_ca_field162niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/null_ca_field163niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_text164niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_text165niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive166niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive167niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_text168niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text169niks3-go-unit-tests> === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method170niks3-go-unit-tests> === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method171niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths172niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths173niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths174niks3-go-unit-tests> === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths175niks3-go-unit-tests> === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths176niks3-go-unit-tests> === CONT  TestParsePathInfoJSON177niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Nix_format178niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Nix_format179niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/Lix_format180niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/Lix_format181niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/empty_input182niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility183niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)184niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/empty_input185niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/whitespace_only186niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)187niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon188niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon189niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI190niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/whitespace_only191niks3-go-unit-tests> === RUN   TestParsePathInfoJSON/invalid_JSON192niks3-go-unit-tests> === PAUSE TestParsePathInfoJSON/invalid_JSON193niks3-go-unit-tests> === RUN   TestSetClientTLS/rejects_connection_without_client_cert194niks3-go-unit-tests> === PAUSE TestSetClientTLS/rejects_connection_without_client_cert195niks3-go-unit-tests> === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA196niks3-go-unit-tests> === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA197niks3-go-unit-tests> === RUN   TestSetClientTLS/preserves_debug_logging_transport198niks3-go-unit-tests> === CONT  TestGetStorePathHash199niks3-go-unit-tests> === RUN   TestGetStorePathHash/valid_store_path200niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI201niks3-go-unit-tests> === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512202niks3-go-unit-tests> === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512203niks3-go-unit-tests> --- PASS: TestFileTokenReadsAndCaches (0.01s)204niks3-go-unit-tests> === CONT  TestConvertHashToNix32205niks3-go-unit-tests> === RUN   TestConvertHashToNix32/SRI_format_to_Nix32206niks3-go-unit-tests> === CONT  TestEncodeNixBase32WithRealHash207niks3-go-unit-tests> === PAUSE TestSetClientTLS/preserves_debug_logging_transport208niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32209niks3-go-unit-tests> === RUN   TestConvertHashToNix32/already_Nix32_format210niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/already_Nix32_format211niks3-go-unit-tests> === RUN   TestConvertHashToNix32/invalid_format212niks3-go-unit-tests> === PAUSE TestConvertHashToNix32/invalid_format213niks3-go-unit-tests> === PAUSE TestGetStorePathHash/valid_store_path214niks3-go-unit-tests> === RUN   TestGetStorePathHash/basename_without_hyphen_should_error215niks3-go-unit-tests> === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error216niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error217niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error218niks3-go-unit-tests> === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error219niks3-go-unit-tests> === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error220niks3-go-unit-tests> === CONT  TestDumpPathSingleFile221niks3-go-unit-tests> === CONT  TestDumpPathMatchesNix222niks3-go-unit-tests> === CONT  TestEncodeNixBase32223niks3-go-unit-tests> === RUN   TestEncodeNixBase32/test_string_hash224niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/test_string_hash225niks3-go-unit-tests> === RUN   TestEncodeNixBase32/empty_input226niks3-go-unit-tests> === PAUSE TestEncodeNixBase32/empty_input227niks3-go-unit-tests> === CONT  TestDumpPathWriterError228niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer229niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/exists230niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/exists231niks3-go-unit-tests> === RUN   TestUploadMultipart_SupersededByPeer/missing232niks3-go-unit-tests> === PAUSE TestUploadMultipart_SupersededByPeer/missing233niks3-go-unit-tests> === CONT  TestPartSizeForNAR234niks3-go-unit-tests> === RUN   TestPartSizeForNAR/zero_stays_at_minimum235niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/zero_stays_at_minimum236niks3-go-unit-tests> === RUN   TestPartSizeForNAR/small_stays_at_minimum237niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/small_stays_at_minimum238niks3-go-unit-tests> === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum239niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum240niks3-go-unit-tests> === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts241niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts242niks3-go-unit-tests> === RUN   TestPartSizeForNAR/1_TiB243niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/1_TiB244niks3-go-unit-tests> === RUN   TestPartSizeForNAR/5_TiB_S3_max_object245niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object246niks3-go-unit-tests> === RUN   TestPartSizeForNAR/capped_at_5_GiB247niks3-go-unit-tests> === PAUSE TestPartSizeForNAR/capped_at_5_GiB248niks3-go-unit-tests> --- PASS: TestEncodeNixBase32WithRealHash (0.00s)249niks3-go-unit-tests> === CONT  TestUploadMultipart_PartsInParallel250niks3-go-unit-tests> --- PASS: TestScriptTokenEmptyToken (0.03s)251niks3-go-unit-tests> === CONT  TestFilterOversizedClosures252niks3-go-unit-tests> === RUN   TestFilterOversizedClosures/no_limit_keeps_everything253niks3-go-unit-tests> === PAUSE TestFilterOversizedClosures/no_limit_keeps_everything254niks3-go-unit-tests> === RUN   TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped255niks3-go-unit-tests> === PAUSE TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped256niks3-go-unit-tests> === RUN   TestFilterOversizedClosures/all_closures_skipped257niks3-go-unit-tests> === PAUSE TestFilterOversizedClosures/all_closures_skipped258niks3-go-unit-tests> === CONT  TestCaseHackSuffix259niks3-go-unit-tests> --- PASS: TestScriptTokenBadJSON (0.04s)260niks3-go-unit-tests> === CONT  TestRegisterUploadedObjectReusesConnections261niks3-go-unit-tests> --- PASS: TestScriptTokenCachesUntilRefresh (0.06s)262niks3-go-unit-tests> === CONT  TestClientSignaturesByStorePath263niks3-go-unit-tests> --- PASS: TestClientSignaturesByStorePath (0.00s)264niks3-go-unit-tests> === CONT  TestStreamPushRequestLine265niks3-go-unit-tests> 2026/10/01 10:24:40 ERROR Upload failed error=boom count=1266niks3-go-unit-tests> --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.05s)267niks3-go-unit-tests> === CONT  TestStreamPushReportsSignatures268niks3-go-unit-tests> 2026/10/01 10:24:40 ERROR Upload failed error=boom count=1269niks3-go-unit-tests> --- PASS: TestStreamPushReportsSignatures (0.00s)270niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/429_enables_limiter271niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Rate limiter enabled after throttle name=server-test rate=5272niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:61421273niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Rate limiter backed off name=server-test rate=5274niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_cert_file275niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter276niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter277niks3-go-unit-tests> === CONT  TestRateLimiterFeedback/503_enables_limiter278niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Rate limiter enabled after throttle name=server-test rate=5279niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:61427280niks3-go-unit-tests> --- PASS: TestRegisterUploadedObjectReusesConnections (0.02s)281niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_ca_file282niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Rate limiter backed off name=server-test rate=5283niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/invalid_ca_file284niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback (0.00s)285niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)286niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)287niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)288niks3-go-unit-tests>     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)289niks3-go-unit-tests> === CONT  TestSetClientTLSErrors/missing_key_file290niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/null_ca_field291niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_text292niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths293niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method294niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/new_structured_format_-_text295niks3-go-unit-tests> === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive296niks3-go-unit-tests> === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths297niks3-go-unit-tests> --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)298niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)299niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)300niks3-go-unit-tests> --- PASS: TestPathInfoCACompatibility (0.00s)301niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)302niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)303niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)304niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)305niks3-go-unit-tests>     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)306niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/whitespace_only307niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/empty_input308niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Lix_format309niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/invalid_JSON310niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)311niks3-go-unit-tests> === CONT  TestParsePathInfoJSON/Nix_format312niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI313niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512314niks3-go-unit-tests> === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon315niks3-go-unit-tests> === CONT  TestSetClientTLS/rejects_connection_without_client_cert316niks3-go-unit-tests> === CONT  TestSetClientTLS/preserves_debug_logging_transport317niks3-go-unit-tests> --- PASS: TestPathInfoHashCompatibility (0.00s)318niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)319niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)320niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)321niks3-go-unit-tests>     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)322niks3-go-unit-tests> --- PASS: TestParsePathInfoJSON (0.00s)323niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)324niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)325niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)326niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)327niks3-go-unit-tests>     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)328niks3-go-unit-tests> --- PASS: TestStreamPushRequestLine (0.01s)329niks3-go-unit-tests> === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA330niks3-go-unit-tests> === CONT  TestConvertHashToNix32/SRI_format_to_Nix32331niks3-go-unit-tests> === CONT  TestConvertHashToNix32/invalid_format332niks3-go-unit-tests> === CONT  TestConvertHashToNix32/already_Nix32_format333niks3-go-unit-tests> --- PASS: TestConvertHashToNix32 (0.00s)334niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)335niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)336niks3-go-unit-tests>     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)337niks3-go-unit-tests> === CONT  TestGetStorePathHash/valid_store_path338niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error339niks3-go-unit-tests> === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error340niks3-go-unit-tests> === CONT  TestGetStorePathHash/basename_without_hyphen_should_error341niks3-go-unit-tests> --- PASS: TestGetStorePathHash (0.00s)342niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)343niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)344niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)345niks3-go-unit-tests>     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)346niks3-go-unit-tests> === CONT  TestEncodeNixBase32/test_string_hash347niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/exists348niks3-go-unit-tests> === CONT  TestEncodeNixBase32/empty_input349niks3-go-unit-tests> --- PASS: TestEncodeNixBase32 (0.00s)350niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)351niks3-go-unit-tests>     --- PASS: TestEncodeNixBase32/empty_input (0.00s)352niks3-go-unit-tests> === CONT  TestUploadMultipart_SupersededByPeer/missing353niks3-go-unit-tests> === CONT  TestPartSizeForNAR/zero_stays_at_minimum354niks3-go-unit-tests> === CONT  TestPartSizeForNAR/capped_at_5_GiB355niks3-go-unit-tests> === CONT  TestPartSizeForNAR/5_TiB_S3_max_object356niks3-go-unit-tests> === CONT  TestPartSizeForNAR/1_TiB357niks3-go-unit-tests> === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts358niks3-go-unit-tests> === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum359niks3-go-unit-tests> === CONT  TestPartSizeForNAR/small_stays_at_minimum360niks3-go-unit-tests> --- PASS: TestPartSizeForNAR (0.00s)361niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)362niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)363niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)364niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)365niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)366niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)367niks3-go-unit-tests>     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)368niks3-go-unit-tests> === CONT  TestFilterOversizedClosures/no_limit_keeps_everything369niks3-go-unit-tests> === CONT  TestFilterOversizedClosures/all_closures_skipped370niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Skipping closure: path exceeds server max NAR size top_level_path=/nix/store/cccccccccccccccccccccccccccccccc-wrapper oversized_path=/nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-small nar_size=1000 max_nar_size=50371niks3-go-unit-tests> === CONT  TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped372niks3-go-unit-tests> 2026/10/01 10:24:40 WARN Skipping closure: path exceeds server max NAR size top_level_path=/nix/store/cccccccccccccccccccccccccccccccc-wrapper oversized_path=/nix/store/bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb-vm-image nar_size=5000 max_nar_size=2000373niks3-go-unit-tests> --- PASS: TestFilterOversizedClosures (0.00s)374niks3-go-unit-tests>     --- PASS: TestFilterOversizedClosures/no_limit_keeps_everything (0.00s)375niks3-go-unit-tests>     --- PASS: TestFilterOversizedClosures/all_closures_skipped (0.00s)376niks3-go-unit-tests>     --- PASS: TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped (0.00s)377niks3-go-unit-tests> --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)378niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)379niks3-go-unit-tests>     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)380niks3-go-unit-tests> 2026/10/01 10:24:40 http: TLS handshake error from 127.0.0.1:61429: remote error: tls: bad certificate381niks3-go-unit-tests> --- PASS: TestSetClientTLSErrors (0.02s)382niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)383niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)384niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)385niks3-go-unit-tests>     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)386niks3-go-unit-tests> --- PASS: TestSetClientTLS (0.03s)387niks3-go-unit-tests>     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)388niks3-go-unit-tests>     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)389niks3-go-unit-tests>     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)390niks3-go-unit-tests> --- PASS: TestDumpPathSingleFile (0.07s)391niks3-go-unit-tests> --- PASS: TestCaseHackSuffix (0.06s)392niks3-go-unit-tests> --- PASS: TestStreamPushBatchesUnderLoad (0.10s)393niks3-go-unit-tests> --- PASS: TestDumpPathMatchesNix (0.42s)394niks3-go-unit-tests> --- PASS: TestDumpPathWriterError (0.43s)395niks3-go-unit-tests> --- PASS: TestUploadMultipart_PartsInParallel (0.61s)396niks3-go-unit-tests> --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)397niks3-go-unit-tests> PASS398niks3-go-unit-tests> Running server tests...399niks3-go-unit-tests> The files belonging to this database system will be owned by user "_nixbld1".400niks3-go-unit-tests> This user must also own the server process.401niks3-go-unit-tests> 402niks3-go-unit-tests> The database cluster will be initialized with locale "C".403niks3-go-unit-tests> The default database encoding has accordingly been set to "SQL_ASCII".404niks3-go-unit-tests> The default text search configuration will be set to "english".405niks3-go-unit-tests> 406niks3-go-unit-tests> Data page checksums are enabled.407niks3-go-unit-tests> 408niks3-go-unit-tests> creating directory /nix/var/nix/builds/nix-41517-960550241/postgres3393098986/data ... ok409niks3-go-unit-tests> creating subdirectories ... ok410niks3-go-unit-tests> selecting dynamic shared memory implementation ... posix411niks3-go-unit-tests> selecting default "max_connections" ... 100412niks3-go-unit-tests> selecting default "shared_buffers" ... 128MB413niks3-go-unit-tests> selecting default time zone ... UTC414niks3-go-unit-tests> creating configuration files ... ok415niks3-go-unit-tests> running bootstrap script ... ok416niks3-go-unit-tests> performing post-bootstrap initialization ... ok417niks3-go-unit-tests> syncing data to disk ... ok418niks3-go-unit-tests> 419niks3-go-unit-tests> initdb: warning: enabling "trust" authentication for local connections420niks3-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.421niks3-go-unit-tests> 422niks3-go-unit-tests> Success. You can now start the database server using:423niks3-go-unit-tests> 424niks3-go-unit-tests>     pg_ctl -D /nix/var/nix/builds/nix-41517-960550241/postgres3393098986/data -l logfile start425niks3-go-unit-tests> 426niks3-go-unit-tests> /nix/var/nix/builds/nix-41517-960550241/postgres3393098986:5432 - no response427niks3-go-unit-tests> 2026-10-01 10:24:47.004 UTC [41820] LOG:  starting PostgreSQL 18.6 on aarch64-apple-darwin25.6.0, compiled by clang version 21.1.8, 64-bit428niks3-go-unit-tests> 2026-10-01 10:24:47.016 UTC [41820] LOG:  listening on Unix socket "/nix/var/nix/builds/nix-41517-960550241/postgres3393098986/.s.PGSQL.5432"429niks3-go-unit-tests> 2026-10-01 10:24:47.040 UTC [41827] LOG:  database system was shut down at 2026-10-01 10:24:46 UTC430niks3-go-unit-tests> 2026-10-01 10:24:47.045 UTC [41820] LOG:  database system is ready to accept connections431niks3-go-unit-tests> /nix/var/nix/builds/nix-41517-960550241/postgres3393098986:5432 - accepting connections432niks3-go-unit-tests> {"timestamp":"2026-10-01T10:24:47.303338Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"88eb68bc-7a68-4720-999e-2cb3bb07bac9","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(4)"}433niks3-go-unit-tests> {"timestamp":"2026-10-01T10:24:47.408882Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"2bf02a2e-369b-45ec-88ef-e989c5d41a39","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(4)"}434niks3-go-unit-tests> {"timestamp":"2026-10-01T10:24:47.512919Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"6044034e-369f-4f4e-905b-706c659b527b","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(10)"}435niks3-go-unit-tests> {"timestamp":"2026-10-01T10:24:47.618052Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"90f98fb5-eeab-4035-acbd-56a498fe5656","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(10)"}436niks3-go-unit-tests> {"timestamp":"2026-10-01T10:24:47.721311Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"f2bd442b-4efe-4c7d-b380-c398cd42d1ad","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(10)"}437niks3-go-unit-tests> {"timestamp":"2026-10-01T10:24:47.825459Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"2c308848-591a-41de-9905-9c2e99907eea","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(9)"}438niks3-go-unit-tests> === RUN   TestService_AuthMiddleware439niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware440niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSProxyHeader441niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSProxyHeader442niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_MTLSBoundSubjects443niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects444niks3-go-unit-tests> === RUN   TestService_ReadAuthMiddleware445niks3-go-unit-tests> === PAUSE TestService_ReadAuthMiddleware446niks3-go-unit-tests> === RUN   TestService_AuthMiddleware_OIDC447niks3-go-unit-tests> === PAUSE TestService_AuthMiddleware_OIDC448niks3-go-unit-tests> === RUN   TestService_RequireScope_OIDC449niks3-go-unit-tests> === PAUSE TestService_RequireScope_OIDC450niks3-go-unit-tests> === RUN   TestService_ReadScope_PublicByDefault451niks3-go-unit-tests> === PAUSE TestService_ReadScope_PublicByDefault452niks3-go-unit-tests> === RUN   TestCacheConfigHandler453niks3-go-unit-tests> === PAUSE TestCacheConfigHandler454niks3-go-unit-tests> === RUN   TestCacheStatsHandler455niks3-go-unit-tests> === PAUSE TestCacheStatsHandler456niks3-go-unit-tests> === RUN   TestClientCADerivations457niks3-go-unit-tests> === PAUSE TestClientCADerivations458niks3-go-unit-tests> === RUN   TestClientErrorHandling459niks3-go-unit-tests> === PAUSE TestClientErrorHandling460niks3-go-unit-tests> === RUN   TestClientIntegration461niks3-go-unit-tests> === PAUSE TestClientIntegration462niks3-go-unit-tests> === RUN   TestClientMultipleUploads463niks3-go-unit-tests> === PAUSE TestClientMultipleUploads464niks3-go-unit-tests> === RUN   TestClientWithDependencies465niks3-go-unit-tests> === PAUSE TestClientWithDependencies466niks3-go-unit-tests> === RUN   TestClientSharedPathCommittedMidPush467niks3-go-unit-tests> === PAUSE TestClientSharedPathCommittedMidPush468niks3-go-unit-tests> === RUN   TestPinProtectsFromGC469niks3-go-unit-tests> === PAUSE TestPinProtectsFromGC470niks3-go-unit-tests> === RUN   TestClientPushesUseOnePush471niks3-go-unit-tests> === PAUSE TestClientPushesUseOnePush472niks3-go-unit-tests> === RUN   TestClientFallsBackToClosures473niks3-go-unit-tests> === PAUSE TestClientFallsBackToClosures474niks3-go-unit-tests> === RUN   TestConcurrentCommitsSharingObjectsDoNotDeadlock475niks3-go-unit-tests> === PAUSE TestConcurrentCommitsSharingObjectsDoNotDeadlock476niks3-go-unit-tests> === RUN   TestResolveDBConnectionString477niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString478niks3-go-unit-tests> === RUN   TestLeadElectsOneAndHandsOver479niks3-go-unit-tests> === PAUSE TestLeadElectsOneAndHandsOver480niks3-go-unit-tests> === RUN   TestLeadIncumbentWinsAfterRestart481niks3-go-unit-tests> 2026-10-01 10:24:50.783 UTC [41920] ERROR:  relation "goose_db_version" does not exist at character 36482niks3-go-unit-tests> 2026-10-01 10:24:50.783 UTC [41920] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC483niks3-go-unit-tests> 2026/10/01 10:24:51 OK   20241026095416_initial_model.sql (259ms)484niks3-go-unit-tests> 2026/10/01 10:24:51 OK   20251210153512_drop_unused_gin_index.sql (7.24ms)485niks3-go-unit-tests> 2026/10/01 10:24:51 OK   20251218171726_add_pins.sql (37ms)486niks3-go-unit-tests> 2026/10/01 10:24:51 OK   20260628120000_add_object_size_and_stats.sql (39.94ms)487niks3-go-unit-tests> 2026/10/01 10:24:51 OK   20260905000000_add_claims.sql (54.25ms)488niks3-go-unit-tests> 2026/10/01 10:24:51 OK   20260920000000_drop_claims.sql (30.3ms)489niks3-go-unit-tests> 2026/10/01 10:24:51 OK   20260923120000_add_pushes.sql (6.85ms)490niks3-go-unit-tests> 2026/10/01 10:24:51 goose: successfully migrated database to version: 20260923120000491niks3-go-unit-tests> 2026/10/01 10:24:51 OK   1_commit_pending_closure.sql (1.03ms)492niks3-go-unit-tests> 2026/10/01 10:24:51 OK   2_object_stats_trigger.sql (261.21µs)493niks3-go-unit-tests> 2026/10/01 10:24:51 OK   3_commit_push.sql (190.46µs)494niks3-go-unit-tests> 2026/10/01 10:24:51 goose: up to current file version: 3495niks3-go-unit-tests> 2026/10/01 10:24:51 INFO lead: acquired remote=192.0.2.1:1234496niks3-go-unit-tests> 2026/10/01 10:24:52 INFO lead: released remote=192.0.2.1:1234497niks3-go-unit-tests> 2026/10/01 10:24:52 INFO lead: acquired remote=192.0.2.1:1234498niks3-go-unit-tests> 2026/10/01 10:24:52 INFO lead: released remote=192.0.2.1:1234499niks3-go-unit-tests> --- PASS: TestLeadIncumbentWinsAfterRestart (4.27s)500niks3-go-unit-tests> === RUN   TestLeadEndsOnShutdown501niks3-go-unit-tests> === PAUSE TestLeadEndsOnShutdown502niks3-go-unit-tests> === RUN   TestGCAdvisoryLockBlocksConcurrentRun503niks3-go-unit-tests> 2026-10-01 10:24:53.414 UTC [41973] ERROR:  relation "goose_db_version" does not exist at character 36504niks3-go-unit-tests> 2026-10-01 10:24:53.414 UTC [41973] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC505niks3-go-unit-tests> 2026/10/01 10:24:53 OK   20241026095416_initial_model.sql (126.87ms)506niks3-go-unit-tests> 2026/10/01 10:24:53 OK   20251210153512_drop_unused_gin_index.sql (13.96ms)507niks3-go-unit-tests> 2026/10/01 10:24:53 OK   20251218171726_add_pins.sql (31.36ms)508niks3-go-unit-tests> 2026/10/01 10:24:53 OK   20260628120000_add_object_size_and_stats.sql (23.3ms)509niks3-go-unit-tests> 2026/10/01 10:24:53 OK   20260905000000_add_claims.sql (21.76ms)510niks3-go-unit-tests> 2026/10/01 10:24:53 OK   20260920000000_drop_claims.sql (40.41ms)511niks3-go-unit-tests> 2026/10/01 10:24:53 OK   20260923120000_add_pushes.sql (6.48ms)512niks3-go-unit-tests> 2026/10/01 10:24:53 goose: successfully migrated database to version: 20260923120000513niks3-go-unit-tests> 2026/10/01 10:24:53 OK   1_commit_pending_closure.sql (1.07ms)514niks3-go-unit-tests> 2026/10/01 10:24:53 OK   2_object_stats_trigger.sql (243.67µs)515niks3-go-unit-tests> 2026/10/01 10:24:53 OK   3_commit_push.sql (186.38µs)516niks3-go-unit-tests> 2026/10/01 10:24:53 goose: up to current file version: 3517niks3-go-unit-tests> --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (1.77s)518niks3-go-unit-tests> === RUN   TestGCBugBareHashReferences519niks3-go-unit-tests> === PAUSE TestGCBugBareHashReferences520niks3-go-unit-tests> === RUN   TestGCMetrics521niks3-go-unit-tests> === PAUSE TestGCMetrics522niks3-go-unit-tests> === RUN   TestPushDedupSurvivesConcurrentGC523niks3-go-unit-tests> === PAUSE TestPushDedupSurvivesConcurrentGC524niks3-go-unit-tests> === RUN   TestDeduplicatedObjectsRecordedAsPending525niks3-go-unit-tests> === PAUSE TestDeduplicatedObjectsRecordedAsPending526niks3-go-unit-tests> === RUN   TestGCSweepSkipsPendingObjects527niks3-go-unit-tests> === PAUSE TestGCSweepSkipsPendingObjects528niks3-go-unit-tests> === RUN   TestTombstonedObjectOfferedWithoutWaiting529niks3-go-unit-tests> === PAUSE TestTombstonedObjectOfferedWithoutWaiting530niks3-go-unit-tests> === RUN   TestGCSweepDeliversEachKeyOnce531niks3-go-unit-tests> === PAUSE TestGCSweepDeliversEachKeyOnce532niks3-go-unit-tests> === RUN   TestCreatePendingClosureVerifyS3FailureReleasesConnection533niks3-go-unit-tests> === PAUSE TestCreatePendingClosureVerifyS3FailureReleasesConnection534niks3-go-unit-tests> === RUN   TestForceGCDuringPushOffersSweptObject535niks3-go-unit-tests> === PAUSE TestForceGCDuringPushOffersSweptObject536niks3-go-unit-tests> === RUN   TestFailedS3DeleteKeepsTombstone537niks3-go-unit-tests> === PAUSE TestFailedS3DeleteKeepsTombstone538niks3-go-unit-tests> === RUN   TestSweepSkipsRowsLockedByPush539niks3-go-unit-tests> === PAUSE TestSweepSkipsRowsLockedByPush540niks3-go-unit-tests> === RUN   TestRegisterCompletedObjectNeedsPendingRow541niks3-go-unit-tests> === PAUSE TestRegisterCompletedObjectNeedsPendingRow542niks3-go-unit-tests> === RUN   TestGetExistingObjectsLocksRows543niks3-go-unit-tests> === PAUSE TestGetExistingObjectsLocksRows544niks3-go-unit-tests> === RUN   TestSweepRechecksPendingAfterLocking545niks3-go-unit-tests> === PAUSE TestSweepRechecksPendingAfterLocking546niks3-go-unit-tests> === RUN   TestPushWaitsForSweepHoldingRow547niks3-go-unit-tests> === PAUSE TestPushWaitsForSweepHoldingRow548niks3-go-unit-tests> === RUN   TestCommitRacingPendingCleanupKeepsObjects549niks3-go-unit-tests> === PAUSE TestCommitRacingPendingCleanupKeepsObjects550niks3-go-unit-tests> === RUN   TestGCEndsOnShutdown551niks3-go-unit-tests> === PAUSE TestGCEndsOnShutdown552niks3-go-unit-tests> === RUN   TestGCTaskStore_StartNew553niks3-go-unit-tests> === PAUSE TestGCTaskStore_StartNew554niks3-go-unit-tests> === RUN   TestGCTaskStore_DeduplicateSameParams555niks3-go-unit-tests> === PAUSE TestGCTaskStore_DeduplicateSameParams556niks3-go-unit-tests> === RUN   TestGCTaskStore_ConflictDifferentParams557niks3-go-unit-tests> === PAUSE TestGCTaskStore_ConflictDifferentParams558niks3-go-unit-tests> === RUN   TestGCTaskStore_GetEmpty559niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetEmpty560niks3-go-unit-tests> === RUN   TestGCTaskStore_GetReturnsLatest561niks3-go-unit-tests> === PAUSE TestGCTaskStore_GetReturnsLatest562niks3-go-unit-tests> === RUN   TestGCTaskStore_CompletedAllowsNewTask563niks3-go-unit-tests> === PAUSE TestGCTaskStore_CompletedAllowsNewTask564niks3-go-unit-tests> === RUN   TestGCTaskStore_PhaseUpdates565niks3-go-unit-tests> === PAUSE TestGCTaskStore_PhaseUpdates566niks3-go-unit-tests> === RUN   TestGCTaskStore_Fail567niks3-go-unit-tests> === PAUSE TestGCTaskStore_Fail568niks3-go-unit-tests> === RUN   TestGracefulShutdownDrainsInflight569niks3-go-unit-tests> === PAUSE TestGracefulShutdownDrainsInflight570niks3-go-unit-tests> === RUN   TestService_healthCheckHandler571niks3-go-unit-tests> === PAUSE TestService_healthCheckHandler572niks3-go-unit-tests> === RUN   TestService_readinessHandler573niks3-go-unit-tests> === PAUSE TestService_readinessHandler574niks3-go-unit-tests> === RUN   TestGenerateLandingPage575niks3-go-unit-tests> === PAUSE TestGenerateLandingPage576niks3-go-unit-tests> === RUN   TestCacheConfigHandlerMaxNarSize577niks3-go-unit-tests> === PAUSE TestCacheConfigHandlerMaxNarSize578niks3-go-unit-tests> === RUN   TestCreatePendingClosureRejectsOversizedNAR579niks3-go-unit-tests> === PAUSE TestCreatePendingClosureRejectsOversizedNAR580niks3-go-unit-tests> === RUN   TestNARDeduplicationMetadataUploadBug581niks3-go-unit-tests> === PAUSE TestNARDeduplicationMetadataUploadBug582niks3-go-unit-tests> === RUN   TestMetricsInventory583niks3-go-unit-tests> === PAUSE TestMetricsInventory584niks3-go-unit-tests> === RUN   TestService_NativeMTLS585niks3-go-unit-tests> === PAUSE TestService_NativeMTLS586niks3-go-unit-tests> === RUN   TestServerTLSConfig587niks3-go-unit-tests> === PAUSE TestServerTLSConfig588niks3-go-unit-tests> === RUN   TestMultipartCleanup589niks3-go-unit-tests> === PAUSE TestMultipartCleanup590niks3-go-unit-tests> === RUN   TestMultipartUploadAbortedWhenCancelledBeforeRecorded591niks3-go-unit-tests> === PAUSE TestMultipartUploadAbortedWhenCancelledBeforeRecorded592niks3-go-unit-tests> === RUN   TestPendingCleanupUsesOneCutoff593niks3-go-unit-tests> === PAUSE TestPendingCleanupUsesOneCutoff594niks3-go-unit-tests> === RUN   TestObjectStatsTrigger595niks3-go-unit-tests> === PAUSE TestObjectStatsTrigger596niks3-go-unit-tests> === RUN   TestOrphanedObjectsGC597niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGC598niks3-go-unit-tests> === RUN   TestOrphanedObjectsGCStressTest599niks3-go-unit-tests> === PAUSE TestOrphanedObjectsGCStressTest600niks3-go-unit-tests> === RUN   TestCreatePin_ReservedPins601niks3-go-unit-tests> === PAUSE TestCreatePin_ReservedPins602niks3-go-unit-tests> === RUN   TestPresentReportsOnlyClosureRoots603niks3-go-unit-tests> === PAUSE TestPresentReportsOnlyClosureRoots604niks3-go-unit-tests> === RUN   TestPresentNotReportedWhileGCDeletesClosure605niks3-go-unit-tests> === PAUSE TestPresentNotReportedWhileGCDeletesClosure606niks3-go-unit-tests> === RUN   TestParseSingleRange607niks3-go-unit-tests> === PAUSE TestParseSingleRange608niks3-go-unit-tests> === RUN   TestProxyHeadersOnlyTrustedOnSocket609niks3-go-unit-tests> === PAUSE TestProxyHeadersOnlyTrustedOnSocket610niks3-go-unit-tests> === RUN   TestIsValidCachePath611niks3-go-unit-tests> === PAUSE TestIsValidCachePath612niks3-go-unit-tests> === RUN   TestReadProxyNarinfo613niks3-go-unit-tests> === PAUSE TestReadProxyNarinfo614niks3-go-unit-tests> === RUN   TestReadProxyNarinfoAlreadyDecompressed615niks3-go-unit-tests> === PAUSE TestReadProxyNarinfoAlreadyDecompressed616niks3-go-unit-tests> === RUN   TestReadProxyNarStreaming617niks3-go-unit-tests> === PAUSE TestReadProxyNarStreaming618niks3-go-unit-tests> === RUN   TestReadProxy404619niks3-go-unit-tests> === PAUSE TestReadProxy404620niks3-go-unit-tests> === RUN   TestReadProxyInvalidPath621niks3-go-unit-tests> === PAUSE TestReadProxyInvalidPath622niks3-go-unit-tests> === RUN   TestReadProxyHead623niks3-go-unit-tests> === PAUSE TestReadProxyHead624niks3-go-unit-tests> === RUN   TestReadProxyOutlastsServerWriteTimeout625niks3-go-unit-tests> === PAUSE TestReadProxyOutlastsServerWriteTimeout626niks3-go-unit-tests> === RUN   TestReadProxyConditionalGet627niks3-go-unit-tests> === PAUSE TestReadProxyConditionalGet628niks3-go-unit-tests> === RUN   TestReadProxyRootRedirectsToIndexHTML629niks3-go-unit-tests> === PAUSE TestReadProxyRootRedirectsToIndexHTML630niks3-go-unit-tests> === RUN   TestReadProxyDisabled631niks3-go-unit-tests> === PAUSE TestReadProxyDisabled632niks3-go-unit-tests> === RUN   TestReadRedirectNar633niks3-go-unit-tests> === PAUSE TestReadRedirectNar634niks3-go-unit-tests> === RUN   TestReadRedirectKeepsNarinfoProxied635niks3-go-unit-tests> === PAUSE TestReadRedirectKeepsNarinfoProxied636niks3-go-unit-tests> === RUN   TestReadProxyRangeRequest637niks3-go-unit-tests> === PAUSE TestReadProxyRangeRequest638niks3-go-unit-tests> === RUN   TestReadRedirectUsesPublicS3URL639niks3-go-unit-tests> === PAUSE TestReadRedirectUsesPublicS3URL640niks3-go-unit-tests> === RUN   TestPush_OverlappingRootsStoreOneRowPerKey641niks3-go-unit-tests> === PAUSE TestPush_OverlappingRootsStoreOneRowPerKey642niks3-go-unit-tests> === RUN   TestPush_CompleteCommitsEveryRoot643niks3-go-unit-tests> === PAUSE TestPush_CompleteCommitsEveryRoot644niks3-go-unit-tests> === RUN   TestPush_SkippedKeySurvivesGCBeforeCommit645niks3-go-unit-tests> === PAUSE TestPush_SkippedKeySurvivesGCBeforeCommit646niks3-go-unit-tests> === RUN   TestPush_RejectsBadRequests647niks3-go-unit-tests> === PAUSE TestPush_RejectsBadRequests648niks3-go-unit-tests> === RUN   TestPush_SignsNarinfosOfItsPendingObjects649niks3-go-unit-tests> === PAUSE TestPush_SignsNarinfosOfItsPendingObjects650niks3-go-unit-tests> === RUN   TestRedundantMultipartUpload651niks3-go-unit-tests> === PAUSE TestRedundantMultipartUpload652niks3-go-unit-tests> === RUN   TestCompleteMultipartUpload_ErrorButObjectExists653niks3-go-unit-tests> === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists654niks3-go-unit-tests> === RUN   TestCompletedNarNotReofferedAcrossClosures655niks3-go-unit-tests> === PAUSE TestCompletedNarNotReofferedAcrossClosures656niks3-go-unit-tests> === RUN   TestPresignedUploadRegisteredBeforeCommit657niks3-go-unit-tests> === PAUSE TestPresignedUploadRegisteredBeforeCommit658niks3-go-unit-tests> === RUN   TestService_Rustfstest659niks3-go-unit-tests> === PAUSE TestService_Rustfstest660niks3-go-unit-tests> === RUN   TestParseSize661niks3-go-unit-tests> === PAUSE TestParseSize662niks3-go-unit-tests> === RUN   TestSkippedUploadsHandler663niks3-go-unit-tests> === PAUSE TestSkippedUploadsHandler664niks3-go-unit-tests> === RUN   TestSystemdListenerNotActivated665niks3-go-unit-tests> --- PASS: TestSystemdListenerNotActivated (0.00s)666niks3-go-unit-tests> === RUN   TestWatchdogBeatsWhenHealthy667niks3-go-unit-tests> --- PASS: TestWatchdogBeatsWhenHealthy (0.04s)668niks3-go-unit-tests> === RUN   TestWatchdogSkipsWhenUnhealthy669niks3-go-unit-tests> 2026/10/01 10:24:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"670niks3-go-unit-tests> 2026/10/01 10:24:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"671niks3-go-unit-tests> 2026/10/01 10:24:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"672niks3-go-unit-tests> 2026/10/01 10:24:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"673niks3-go-unit-tests> 2026/10/01 10:24:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"674niks3-go-unit-tests> 2026/10/01 10:24:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"675niks3-go-unit-tests> 2026/10/01 10:24:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"676niks3-go-unit-tests> 2026/10/01 10:24:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"677niks3-go-unit-tests> 2026/10/01 10:24:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"678niks3-go-unit-tests> 2026/10/01 10:24:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"679niks3-go-unit-tests> --- PASS: TestWatchdogSkipsWhenUnhealthy (0.23s)680niks3-go-unit-tests> === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle681niks3-go-unit-tests> === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle682niks3-go-unit-tests> === RUN   TestProxyWriteTimeout683niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout684niks3-go-unit-tests> === RUN   TestPendingClosureWriteTimeout685niks3-go-unit-tests> === PAUSE TestPendingClosureWriteTimeout686niks3-go-unit-tests> === RUN   TestIsValidUploadKey687niks3-go-unit-tests> === PAUSE TestIsValidUploadKey688niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys689niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys690niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody691niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody692niks3-go-unit-tests> === RUN   TestService_cleanupPendingClosuresHandler693niks3-go-unit-tests> === PAUSE TestService_cleanupPendingClosuresHandler694niks3-go-unit-tests> === RUN   TestService_createPendingClosureHandler695niks3-go-unit-tests> === PAUSE TestService_createPendingClosureHandler696niks3-go-unit-tests> === RUN   TestService_verifyS3Integrity697niks3-go-unit-tests> === PAUSE TestService_verifyS3Integrity698niks3-go-unit-tests> === RUN   TestCompleteMultipartUnregistered699niks3-go-unit-tests> === PAUSE TestCompleteMultipartUnregistered700niks3-go-unit-tests> === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT701niks3-go-unit-tests> === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT702niks3-go-unit-tests> === CONT  TestService_AuthMiddleware703niks3-go-unit-tests> === CONT  TestNARDeduplicationMetadataUploadBug704niks3-go-unit-tests> === CONT  TestTombstonedObjectOfferedWithoutWaiting705niks3-go-unit-tests> === CONT  TestReadRedirectKeepsNarinfoProxied706niks3-go-unit-tests> === CONT  TestParseSize707niks3-go-unit-tests> --- PASS: TestParseSize (0.00s)708niks3-go-unit-tests> === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT709niks3-go-unit-tests> === CONT  TestGCSweepSkipsPendingObjects710niks3-go-unit-tests> === CONT  TestDeduplicatedObjectsRecordedAsPending711niks3-go-unit-tests> === CONT  TestPushDedupSurvivesConcurrentGC712niks3-go-unit-tests> === CONT  TestPush_SignsNarinfosOfItsPendingObjects713niks3-go-unit-tests> === CONT  TestPush_CompleteCommitsEveryRoot714niks3-go-unit-tests> 2026-10-01 10:24:56.577 UTC [42086] ERROR:  relation "goose_db_version" does not exist at character 36715niks3-go-unit-tests> 2026-10-01 10:24:56.577 UTC [42086] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC716niks3-go-unit-tests> 2026-10-01 10:24:56.612 UTC [42089] ERROR:  relation "goose_db_version" does not exist at character 36717niks3-go-unit-tests> 2026-10-01 10:24:56.612 UTC [42089] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC718niks3-go-unit-tests> 2026-10-01 10:24:56.612 UTC [42090] ERROR:  relation "goose_db_version" does not exist at character 36719niks3-go-unit-tests> 2026-10-01 10:24:56.612 UTC [42090] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC720niks3-go-unit-tests> 2026-10-01 10:24:56.613 UTC [42087] ERROR:  relation "goose_db_version" does not exist at character 36721niks3-go-unit-tests> 2026-10-01 10:24:56.613 UTC [42087] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC722niks3-go-unit-tests> 2026-10-01 10:24:56.615 UTC [42088] ERROR:  relation "goose_db_version" does not exist at character 36723niks3-go-unit-tests> 2026-10-01 10:24:56.615 UTC [42088] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC724niks3-go-unit-tests> 2026-10-01 10:24:56.615 UTC [42091] ERROR:  relation "goose_db_version" does not exist at character 36725niks3-go-unit-tests> 2026-10-01 10:24:56.615 UTC [42091] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC726niks3-go-unit-tests> 2026-10-01 10:24:56.646 UTC [42092] ERROR:  relation "goose_db_version" does not exist at character 36727niks3-go-unit-tests> 2026-10-01 10:24:56.646 UTC [42092] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC728niks3-go-unit-tests> 2026-10-01 10:24:56.669 UTC [42093] ERROR:  relation "goose_db_version" does not exist at character 36729niks3-go-unit-tests> 2026-10-01 10:24:56.669 UTC [42093] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC730niks3-go-unit-tests> 2026-10-01 10:24:56.670 UTC [42094] ERROR:  relation "goose_db_version" does not exist at character 36731niks3-go-unit-tests> 2026-10-01 10:24:56.670 UTC [42094] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC732niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20241026095416_initial_model.sql (94.09ms)733niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251210153512_drop_unused_gin_index.sql (13.89ms)734niks3-go-unit-tests> 2026-10-01 10:24:56.732 UTC [42095] ERROR:  relation "goose_db_version" does not exist at character 36735niks3-go-unit-tests> 2026-10-01 10:24:56.732 UTC [42095] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC736niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251218171726_add_pins.sql (14.33ms)737niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20241026095416_initial_model.sql (97ms)738niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251210153512_drop_unused_gin_index.sql (14.11ms)739niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20241026095416_initial_model.sql (109.33ms)740niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20241026095416_initial_model.sql (111.24ms)741niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260628120000_add_object_size_and_stats.sql (40.82ms)742niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20241026095416_initial_model.sql (112.93ms)743niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20241026095416_initial_model.sql (110.94ms)744niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251210153512_drop_unused_gin_index.sql (13.04ms)745niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20241026095416_initial_model.sql (131.45ms)746niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260905000000_add_claims.sql (19.88ms)747niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251210153512_drop_unused_gin_index.sql (20.34ms)748niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251218171726_add_pins.sql (24.12ms)749niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251210153512_drop_unused_gin_index.sql (20.37ms)750niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251210153512_drop_unused_gin_index.sql (21.42ms)751niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251218171726_add_pins.sql (10.7ms)752niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251210153512_drop_unused_gin_index.sql (3.22ms)753niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20241026095416_initial_model.sql (94.26ms)754niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260920000000_drop_claims.sql (4.76ms)755niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20241026095416_initial_model.sql (96.65ms)756niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20241026095416_initial_model.sql (47.52ms)757niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251210153512_drop_unused_gin_index.sql (3.75ms)758niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251218171726_add_pins.sql (6.51ms)759niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260628120000_add_object_size_and_stats.sql (5.76ms)760niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251218171726_add_pins.sql (6.71ms)761niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251218171726_add_pins.sql (6.81ms)762niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260628120000_add_object_size_and_stats.sql (7.06ms)763niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251218171726_add_pins.sql (5.13ms)764niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260923120000_add_pushes.sql (4.07ms)765niks3-go-unit-tests> 2026/10/01 10:24:56 goose: successfully migrated database to version: 20260923120000766niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251210153512_drop_unused_gin_index.sql (2.55ms)767niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251210153512_drop_unused_gin_index.sql (4.4ms)768niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251218171726_add_pins.sql (3.52ms)769niks3-go-unit-tests> 2026/10/01 10:24:56 OK   1_commit_pending_closure.sql (3.15ms)770niks3-go-unit-tests> 2026/10/01 10:24:56 OK   2_object_stats_trigger.sql (414.79µs)771niks3-go-unit-tests> 2026/10/01 10:24:56 OK   3_commit_push.sql (334.29µs)772niks3-go-unit-tests> 2026/10/01 10:24:56 goose: up to current file version: 3773niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260628120000_add_object_size_and_stats.sql (22.2ms)774niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260628120000_add_object_size_and_stats.sql (22.21ms)775niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260628120000_add_object_size_and_stats.sql (21.52ms)776niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260628120000_add_object_size_and_stats.sql (22.58ms)777niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260628120000_add_object_size_and_stats.sql (54.74ms)778niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260905000000_add_claims.sql (57.85ms)779niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251218171726_add_pins.sql (61.83ms)780niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20251218171726_add_pins.sql (62.29ms)781niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260905000000_add_claims.sql (70.43ms)782niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260628120000_add_object_size_and_stats.sql (37.45ms)783niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260628120000_add_object_size_and_stats.sql (37.53ms)784niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260905000000_add_claims.sql (88.33ms)785niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260905000000_add_claims.sql (88.35ms)786niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260905000000_add_claims.sql (88.32ms)787niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260920000000_drop_claims.sql (58.55ms)788niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260905000000_add_claims.sql (116.24ms)789niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260920000000_drop_claims.sql (89.22ms)790niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260923120000_add_pushes.sql (28.73ms)791niks3-go-unit-tests> 2026/10/01 10:24:56 goose: successfully migrated database to version: 20260923120000792niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260905000000_add_claims.sql (100.58ms)793niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260920000000_drop_claims.sql (49.24ms)794niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260920000000_drop_claims.sql (49.97ms)795niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260923120000_add_pushes.sql (14.15ms)796niks3-go-unit-tests> 2026/10/01 10:24:56 goose: successfully migrated database to version: 20260923120000797niks3-go-unit-tests> 2026/10/01 10:24:56 OK   1_commit_pending_closure.sql (6ms)798niks3-go-unit-tests> 2026/10/01 10:24:56 OK   1_commit_pending_closure.sql (3.13ms)799niks3-go-unit-tests> 2026/10/01 10:24:56 OK   2_object_stats_trigger.sql (750.38µs)800niks3-go-unit-tests> 2026/10/01 10:24:56 OK   3_commit_push.sql (440.04µs)801niks3-go-unit-tests> 2026/10/01 10:24:56 goose: up to current file version: 3802niks3-go-unit-tests> 2026/10/01 10:24:56 OK   2_object_stats_trigger.sql (531.88µs)803niks3-go-unit-tests> 2026/10/01 10:24:56 OK   3_commit_push.sql (405.75µs)804niks3-go-unit-tests> 2026/10/01 10:24:56 goose: up to current file version: 3805niks3-go-unit-tests> 2026/10/01 10:24:56 OK   20260905000000_add_claims.sql (72.92ms)806niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260920000000_drop_claims.sql (83.56ms)807niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260923120000_add_pushes.sql (36.88ms)808niks3-go-unit-tests> 2026/10/01 10:24:57 goose: successfully migrated database to version: 20260923120000809niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260923120000_add_pushes.sql (38.51ms)810niks3-go-unit-tests> 2026/10/01 10:24:57 goose: successfully migrated database to version: 20260923120000811niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260920000000_drop_claims.sql (59.81ms)812niks3-go-unit-tests> 2026/10/01 10:24:57 OK   1_commit_pending_closure.sql (2.72ms)813niks3-go-unit-tests> 2026/10/01 10:24:57 OK   1_commit_pending_closure.sql (1.89ms)814niks3-go-unit-tests> 2026/10/01 10:24:57 OK   2_object_stats_trigger.sql (355.79µs)815niks3-go-unit-tests> 2026/10/01 10:24:57 OK   2_object_stats_trigger.sql (365.75µs)816niks3-go-unit-tests> 2026/10/01 10:24:57 OK   3_commit_push.sql (278.25µs)817niks3-go-unit-tests> 2026/10/01 10:24:57 goose: up to current file version: 3818niks3-go-unit-tests> 2026/10/01 10:24:57 OK   3_commit_push.sql (284.17µs)819niks3-go-unit-tests> 2026/10/01 10:24:57 goose: up to current file version: 3820niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260905000000_add_claims.sql (136.11ms)821niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260920000000_drop_claims.sql (79.64ms)822niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260923120000_add_pushes.sql (43.61ms)823niks3-go-unit-tests> 2026/10/01 10:24:57 goose: successfully migrated database to version: 20260923120000824niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260923120000_add_pushes.sql (40.32ms)825niks3-go-unit-tests> 2026/10/01 10:24:57 goose: successfully migrated database to version: 20260923120000826niks3-go-unit-tests> 2026/10/01 10:24:57 OK   1_commit_pending_closure.sql (1.53ms)827niks3-go-unit-tests> 2026/10/01 10:24:57 OK   1_commit_pending_closure.sql (1.58ms)828niks3-go-unit-tests> 2026/10/01 10:24:57 OK   2_object_stats_trigger.sql (317.71µs)829niks3-go-unit-tests> 2026/10/01 10:24:57 OK   2_object_stats_trigger.sql (316.08µs)830niks3-go-unit-tests> 2026/10/01 10:24:57 OK   3_commit_push.sql (222.17µs)831niks3-go-unit-tests> 2026/10/01 10:24:57 goose: up to current file version: 3832niks3-go-unit-tests> 2026/10/01 10:24:57 OK   3_commit_push.sql (226.92µs)833niks3-go-unit-tests> 2026/10/01 10:24:57 goose: up to current file version: 3834niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260920000000_drop_claims.sql (72.65ms)835niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260923120000_add_pushes.sql (29.69ms)836niks3-go-unit-tests> 2026/10/01 10:24:57 goose: successfully migrated database to version: 20260923120000837niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260923120000_add_pushes.sql (39.1ms)838niks3-go-unit-tests> 2026/10/01 10:24:57 goose: successfully migrated database to version: 20260923120000839niks3-go-unit-tests> 2026/10/01 10:24:57 OK   1_commit_pending_closure.sql (1.44ms)840niks3-go-unit-tests> 2026/10/01 10:24:57 OK   1_commit_pending_closure.sql (1.44ms)841niks3-go-unit-tests> 2026/10/01 10:24:57 OK   2_object_stats_trigger.sql (317.42µs)842niks3-go-unit-tests> 2026/10/01 10:24:57 OK   2_object_stats_trigger.sql (316µs)843niks3-go-unit-tests> 2026/10/01 10:24:57 OK   3_commit_push.sql (264.17µs)844niks3-go-unit-tests> 2026/10/01 10:24:57 goose: up to current file version: 3845niks3-go-unit-tests> 2026/10/01 10:24:57 OK   3_commit_push.sql (272.29µs)846niks3-go-unit-tests> 2026/10/01 10:24:57 goose: up to current file version: 3847niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260920000000_drop_claims.sql (101.91ms)848niks3-go-unit-tests> 2026/10/01 10:24:57 OK   20260923120000_add_pushes.sql (23.89ms)849niks3-go-unit-tests> 2026/10/01 10:24:57 goose: successfully migrated database to version: 20260923120000850niks3-go-unit-tests> 2026/10/01 10:24:57 OK   1_commit_pending_closure.sql (2.93ms)851niks3-go-unit-tests> 2026/10/01 10:24:57 OK   2_object_stats_trigger.sql (633.08µs)852niks3-go-unit-tests> 2026/10/01 10:24:57 OK   3_commit_push.sql (484.25µs)853niks3-go-unit-tests> 2026/10/01 10:24:57 goose: up to current file version: 3854niks3-go-unit-tests> 2026/10/01 10:24:57 INFO Received uploads request method=POST path=/api/pending_closures855niks3-go-unit-tests> 2026/10/01 10:24:57 INFO Aborted multipart uploads count=0 kept=0856niks3-go-unit-tests> 2026/10/01 10:24:57 WARN Force mode enabled - objects will be deleted immediately without grace period857niks3-go-unit-tests> 2026/10/01 10:24:57 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=0858niks3-go-unit-tests> 2026/10/01 10:24:57 INFO Vacuumed table table=pending_closures859niks3-go-unit-tests> 2026/10/01 10:24:57 INFO Vacuumed table table=pending_objects860niks3-go-unit-tests> 2026/10/01 10:24:57 INFO Vacuumed table table=multipart_uploads861niks3-go-unit-tests> 2026/10/01 10:24:57 INFO Vacuumed table table=closures862niks3-go-unit-tests> 2026/10/01 10:24:57 INFO Vacuumed table table=objects863niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug864niks3-go-unit-tests>     metadata_upload_test.go:48: First store path: /nix/var/nix/builds/nix-41517-960550241/TestNARDeduplicationMetadataUploadBug1933166457/001/store/nm7jpzgw8m6xa2gi5anvyf22dm4hsmhx-file1.txt865niks3-go-unit-tests> --- PASS: TestGCSweepSkipsPendingObjects (3.63s)866niks3-go-unit-tests> === CONT  TestCompleteMultipartUnregistered867niks3-go-unit-tests> 2026/10/01 10:24:57 INFO Received push request method=POST path=/api/pushes868niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)869niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Uploading nm7jpzgw8m6xa2gi5anvyf22dm4hsmhx-file1.txt (160B)870niks3-go-unit-tests> 2026/10/01 10:24:58 WARN Failed to register uploaded object key=nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst error="server returned 404: 404 page not found\n"871niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign872niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Signed narinfos id=1 count=1873niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Uploading 1 narinfos874niks3-go-unit-tests> 2026/10/01 10:24:58 WARN Failed to register uploaded object key=nm7jpzgw8m6xa2gi5anvyf22dm4hsmhx.ls error="server returned 404: 404 page not found\n"875niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Received uploads request method=POST path=/api/pending_closures876niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Received complete push request method=POST path=/api/pushes/1/complete877niks3-go-unit-tests> 2026/10/01 10:24:58 WARN Failed to register uploaded object key=nm7jpzgw8m6xa2gi5anvyf22dm4hsmhx.narinfo error="server returned 404: 404 page not found\n"878niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Upload complete. (292ms)879niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug880niks3-go-unit-tests>     metadata_upload_test.go:54: Retrieved narinfo from S3:881niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-41517-960550241/TestNARDeduplicationMetadataUploadBug1933166457/001/store/nm7jpzgw8m6xa2gi5anvyf22dm4hsmhx-file1.txt882niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst883niks3-go-unit-tests>         Compression: zstd884niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf885niks3-go-unit-tests>         NarSize: 160886niks3-go-unit-tests>         References: 887niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf888niks3-go-unit-tests>     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)889niks3-go-unit-tests>     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):890niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}891niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Aborted multipart uploads count=0 kept=0892niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=0893niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Vacuumed table table=pending_closures894niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Vacuumed table table=pending_objects895niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Vacuumed table table=multipart_uploads896niks3-go-unit-tests>     metadata_upload_test.go:64: Second store path (same content): /nix/var/nix/builds/nix-41517-960550241/TestNARDeduplicationMetadataUploadBug1933166457/001/store/ka6f25mrzx08q3rry92361sif6w5fr10-file2.txt897niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Vacuumed table table=closures898niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Vacuumed table table=objects899niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Aborted multipart uploads count=0 kept=0900niks3-go-unit-tests> 2026/10/01 10:24:58 WARN Force mode enabled - objects will be deleted immediately without grace period901niks3-go-unit-tests> 2026/10/01 10:24:58 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=0902niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Received uploads request method=POST path=/api/pending_closures903niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Vacuumed table table=pending_closures904niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Vacuumed table table=pending_objects905niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Vacuumed table table=multipart_uploads906niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Received push request method=POST path=/api/pushes907niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)908niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Vacuumed table table=closures909niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Vacuumed table table=objects910niks3-go-unit-tests> --- PASS: TestPushDedupSurvivesConcurrentGC (4.35s)911niks3-go-unit-tests> === CONT  TestService_verifyS3Integrity912niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign913niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Signed narinfos id=2 count=1914niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Uploading 1 narinfos915niks3-go-unit-tests> 2026/10/01 10:24:58 WARN Failed to register uploaded object key=ka6f25mrzx08q3rry92361sif6w5fr10.ls error="server returned 404: 404 page not found\n"916niks3-go-unit-tests> --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (4.39s)917niks3-go-unit-tests> === CONT  TestService_createPendingClosureHandler918niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Received complete push request method=POST path=/api/pushes/2/complete919niks3-go-unit-tests> 2026/10/01 10:24:58 WARN Failed to register uploaded object key=ka6f25mrzx08q3rry92361sif6w5fr10.narinfo error="server returned 404: 404 page not found\n"920niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Upload complete. (179ms)921niks3-go-unit-tests> === NAME  TestNARDeduplicationMetadataUploadBug922niks3-go-unit-tests>     metadata_upload_test.go:76: Retrieved narinfo from S3:923niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-41517-960550241/TestNARDeduplicationMetadataUploadBug1933166457/001/store/ka6f25mrzx08q3rry92361sif6w5fr10-file2.txt924niks3-go-unit-tests>         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst925niks3-go-unit-tests>         Compression: zstd926niks3-go-unit-tests>         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf927niks3-go-unit-tests>         NarSize: 160928niks3-go-unit-tests>         References: 929niks3-go-unit-tests>         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf930niks3-go-unit-tests>     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)931niks3-go-unit-tests>     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):932niks3-go-unit-tests>         {"version":1,"root":{"type":"regular","size":44}}933niks3-go-unit-tests> --- PASS: TestNARDeduplicationMetadataUploadBug (4.59s)934niks3-go-unit-tests> === CONT  TestService_cleanupPendingClosuresHandler935niks3-go-unit-tests> 2026/10/01 10:24:58 INFO Received push request method=POST path=/api/pushes936niks3-go-unit-tests> 2026/10/01 10:24:59 INFO Received complete push request method=POST path=/api/pushes/1/complete937niks3-go-unit-tests> --- PASS: TestPush_CompleteCommitsEveryRoot (4.86s)938niks3-go-unit-tests> === CONT  TestUploadHandlersRejectOversizedBody939niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure940niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure941niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart942niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart943niks3-go-unit-tests> === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts944niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts945niks3-go-unit-tests> === CONT  TestUploadHandlersRejectInvalidKeys946niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info947niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info948niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal949niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal950niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key951niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key952niks3-go-unit-tests> === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key953niks3-go-unit-tests> === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key954niks3-go-unit-tests> === CONT  TestIsValidUploadKey955niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo956niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo957niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_zst958niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_zst959niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_xz960niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_xz961niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_plain962niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_plain963niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing964niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing965niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log966niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log967niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_home-manager_file968niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_home-manager_file969niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_plus_in_name970niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_plus_in_name971niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_question_mark972niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_question_mark973niks3-go-unit-tests> === RUN   TestIsValidUploadKey/build_log_equals974niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/build_log_equals975niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation976niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation977niks3-go-unit-tests> === RUN   TestIsValidUploadKey/realisation_plus_in_output978niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/realisation_plus_in_output979niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nix-cache-info980niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nix-cache-info981niks3-go-unit-tests> === RUN   TestIsValidUploadKey/index.html982niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/index.html983niks3-go-unit-tests> === RUN   TestIsValidUploadKey/narinfo_key,_nar_type984niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type985niks3-go-unit-tests> === RUN   TestIsValidUploadKey/nar_key,_narinfo_type986niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type987niks3-go-unit-tests> === RUN   TestIsValidUploadKey/listing_key,_narinfo_type988niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type989niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal990niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal991niks3-go-unit-tests> === RUN   TestIsValidUploadKey/traversal_nar992niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/traversal_nar993niks3-go-unit-tests> === RUN   TestIsValidUploadKey/absolute994niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/absolute995niks3-go-unit-tests> === RUN   TestIsValidUploadKey/empty_key996niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/empty_key997niks3-go-unit-tests> === RUN   TestIsValidUploadKey/unknown_type998niks3-go-unit-tests> === PAUSE TestIsValidUploadKey/unknown_type999niks3-go-unit-tests> === CONT  TestPendingClosureWriteTimeout1000niks3-go-unit-tests> === RUN   TestPendingClosureWriteTimeout/empty1001niks3-go-unit-tests> === PAUSE TestPendingClosureWriteTimeout/empty1002niks3-go-unit-tests> === RUN   TestPendingClosureWriteTimeout/400_objects1003niks3-go-unit-tests> === PAUSE TestPendingClosureWriteTimeout/400_objects1004niks3-go-unit-tests> === RUN   TestPendingClosureWriteTimeout/670k_objects1005niks3-go-unit-tests> === PAUSE TestPendingClosureWriteTimeout/670k_objects1006niks3-go-unit-tests> === RUN   TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline1007niks3-go-unit-tests> === PAUSE TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline1008niks3-go-unit-tests> === CONT  TestProxyWriteTimeout1009niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/narinfo1010niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/narinfo1011niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/1_GiB_nar1012niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/1_GiB_nar1013niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/10_GiB_nar1014niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/10_GiB_nar1015niks3-go-unit-tests> === RUN   TestProxyWriteTimeout/unknown_size1016niks3-go-unit-tests> === PAUSE TestProxyWriteTimeout/unknown_size1017niks3-go-unit-tests> === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1018niks3-go-unit-tests> 2026/10/01 10:24:59 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"1019niks3-go-unit-tests> --- PASS: TestService_AuthMiddleware (5.12s)1020niks3-go-unit-tests> === CONT  TestSkippedUploadsHandler1021niks3-go-unit-tests> 2026/10/01 10:24:59 INFO Client skipped oversized paths paths=3 nar_bytes=50000000001022niks3-go-unit-tests> --- PASS: TestSkippedUploadsHandler (0.00s)1023niks3-go-unit-tests> === CONT  TestPush_RejectsBadRequests1024niks3-go-unit-tests> 2026/10/01 10:24:59 INFO Received uploads request method=POST path=/api/pending_closures1025niks3-go-unit-tests> --- PASS: TestTombstonedObjectOfferedWithoutWaiting (5.62s)1026niks3-go-unit-tests> === CONT  TestReadRedirectUsesPublicS3URL1027niks3-go-unit-tests> 2026/10/01 10:25:00 INFO Received push request method=POST path=/api/pushes1028niks3-go-unit-tests> 2026/10/01 10:25:00 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1029niks3-go-unit-tests> 2026/10/01 10:25:00 INFO Signed narinfos id=1 count=11030niks3-go-unit-tests> --- PASS: TestPush_SignsNarinfosOfItsPendingObjects (6.30s)1031niks3-go-unit-tests> === CONT  TestPush_OverlappingRootsStoreOneRowPerKey1032niks3-go-unit-tests> --- PASS: TestReadRedirectKeepsNarinfoProxied (6.70s)1033niks3-go-unit-tests> === CONT  TestClientMultipleUploads1034niks3-go-unit-tests> 2026/10/01 10:25:01 INFO Received uploads request method=POST path=/api/pending_closures1035niks3-go-unit-tests> --- PASS: TestDeduplicatedObjectsRecordedAsPending (7.32s)1036niks3-go-unit-tests> === CONT  TestGCMetrics1037niks3-go-unit-tests> 2026-10-01 10:25:01.922 UTC [42160] ERROR:  relation "goose_db_version" does not exist at character 361038niks3-go-unit-tests> 2026-10-01 10:25:01.922 UTC [42160] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1039niks3-go-unit-tests> 2026-10-01 10:25:02.335 UTC [42164] ERROR:  relation "goose_db_version" does not exist at character 361040niks3-go-unit-tests> 2026-10-01 10:25:02.335 UTC [42164] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1041niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20241026095416_initial_model.sql (362.92ms)1042niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20251210153512_drop_unused_gin_index.sql (15.65ms)1043niks3-go-unit-tests> 2026-10-01 10:25:02.447 UTC [42165] ERROR:  relation "goose_db_version" does not exist at character 361044niks3-go-unit-tests> 2026-10-01 10:25:02.447 UTC [42165] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1045niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20251218171726_add_pins.sql (15.84ms)1046niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20260628120000_add_object_size_and_stats.sql (74.65ms)1047niks3-go-unit-tests> 2026-10-01 10:25:02.556 UTC [42167] ERROR:  relation "goose_db_version" does not exist at character 361048niks3-go-unit-tests> 2026-10-01 10:25:02.556 UTC [42167] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1049niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20260905000000_add_claims.sql (34.7ms)1050niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20241026095416_initial_model.sql (166.37ms)1051niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20251210153512_drop_unused_gin_index.sql (8.5ms)1052niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20260920000000_drop_claims.sql (62.93ms)1053niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20260923120000_add_pushes.sql (24.91ms)1054niks3-go-unit-tests> 2026/10/01 10:25:02 goose: successfully migrated database to version: 202609231200001055niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20251218171726_add_pins.sql (33.51ms)1056niks3-go-unit-tests> 2026-10-01 10:25:02.648 UTC [42169] ERROR:  relation "goose_db_version" does not exist at character 361057niks3-go-unit-tests> 2026-10-01 10:25:02.648 UTC [42169] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1058niks3-go-unit-tests> 2026/10/01 10:25:02 OK   1_commit_pending_closure.sql (3.65ms)1059niks3-go-unit-tests> 2026/10/01 10:25:02 OK   2_object_stats_trigger.sql (1.82ms)1060niks3-go-unit-tests> 2026/10/01 10:25:02 OK   3_commit_push.sql (1.1ms)1061niks3-go-unit-tests> 2026/10/01 10:25:02 goose: up to current file version: 31062niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20260628120000_add_object_size_and_stats.sql (75.65ms)1063niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20241026095416_initial_model.sql (182.84ms)1064niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20251210153512_drop_unused_gin_index.sql (33.89ms)1065niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20260905000000_add_claims.sql (120.13ms)1066niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20251218171726_add_pins.sql (69.21ms)1067niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20241026095416_initial_model.sql (255.08ms)1068niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20251210153512_drop_unused_gin_index.sql (18.17ms)1069niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20260920000000_drop_claims.sql (55.72ms)1070niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20260628120000_add_object_size_and_stats.sql (56.46ms)1071niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20260923120000_add_pushes.sql (37.07ms)1072niks3-go-unit-tests> 2026/10/01 10:25:02 goose: successfully migrated database to version: 202609231200001073niks3-go-unit-tests> 2026/10/01 10:25:02 OK   1_commit_pending_closure.sql (3.24ms)1074niks3-go-unit-tests> 2026/10/01 10:25:02 OK   2_object_stats_trigger.sql (652.29µs)1075niks3-go-unit-tests> 2026/10/01 10:25:02 OK   3_commit_push.sql (466.38µs)1076niks3-go-unit-tests> 2026/10/01 10:25:02 goose: up to current file version: 31077niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20251218171726_add_pins.sql (50.08ms)1078niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20260905000000_add_claims.sql (49.27ms)1079niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20260920000000_drop_claims.sql (42.65ms)1080niks3-go-unit-tests> 2026/10/01 10:25:02 OK   20241026095416_initial_model.sql (225.78ms)1081niks3-go-unit-tests> 2026/10/01 10:25:03 OK   20251210153512_drop_unused_gin_index.sql (28.34ms)1082niks3-go-unit-tests> 2026/10/01 10:25:03 OK   20260628120000_add_object_size_and_stats.sql (73.96ms)1083niks3-go-unit-tests> 2026/10/01 10:25:03 OK   20260923120000_add_pushes.sql (31.64ms)1084niks3-go-unit-tests> 2026/10/01 10:25:03 goose: successfully migrated database to version: 202609231200001085niks3-go-unit-tests> 2026/10/01 10:25:03 OK   1_commit_pending_closure.sql (3.42ms)1086niks3-go-unit-tests> 2026/10/01 10:25:03 OK   2_object_stats_trigger.sql (694.42µs)1087niks3-go-unit-tests> 2026/10/01 10:25:03 OK   3_commit_push.sql (447.67µs)1088niks3-go-unit-tests> 2026/10/01 10:25:03 goose: up to current file version: 31089niks3-go-unit-tests> 2026/10/01 10:25:03 OK   20251218171726_add_pins.sql (103.13ms)1090niks3-go-unit-tests> 2026/10/01 10:25:03 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1091niks3-go-unit-tests> 2026/10/01 10:25:03 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1092niks3-go-unit-tests> --- PASS: TestCompleteMultipartUnregistered (5.30s)1093niks3-go-unit-tests> === CONT  TestGCBugBareHashReferences1094niks3-go-unit-tests> 2026/10/01 10:25:03 OK   20260905000000_add_claims.sql (164.16ms)1095niks3-go-unit-tests> 2026/10/01 10:25:03 OK   20260628120000_add_object_size_and_stats.sql (100.46ms)1096niks3-go-unit-tests> 2026/10/01 10:25:03 OK   20260920000000_drop_claims.sql (122.09ms)1097niks3-go-unit-tests> 2026/10/01 10:25:03 OK   20260923120000_add_pushes.sql (41.8ms)1098niks3-go-unit-tests> 2026/10/01 10:25:03 goose: successfully migrated database to version: 202609231200001099niks3-go-unit-tests> 2026/10/01 10:25:03 OK   1_commit_pending_closure.sql (2.9ms)1100niks3-go-unit-tests> 2026/10/01 10:25:03 OK   2_object_stats_trigger.sql (598µs)1101niks3-go-unit-tests> 2026/10/01 10:25:03 OK   3_commit_push.sql (439.38µs)1102niks3-go-unit-tests> 2026/10/01 10:25:03 goose: up to current file version: 31103niks3-go-unit-tests> 2026/10/01 10:25:03 OK   20260905000000_add_claims.sql (141.42ms)1104niks3-go-unit-tests> 2026-10-01 10:25:03.430 UTC [42172] ERROR:  relation "goose_db_version" does not exist at character 361105niks3-go-unit-tests> 2026-10-01 10:25:03.430 UTC [42172] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1106niks3-go-unit-tests> 2026/10/01 10:25:03 OK   20260920000000_drop_claims.sql (110.59ms)1107niks3-go-unit-tests> 2026/10/01 10:25:03 OK   20260923120000_add_pushes.sql (65.32ms)1108niks3-go-unit-tests> 2026/10/01 10:25:03 goose: successfully migrated database to version: 202609231200001109niks3-go-unit-tests> 2026/10/01 10:25:03 OK   1_commit_pending_closure.sql (8.84ms)1110niks3-go-unit-tests> 2026/10/01 10:25:03 OK   2_object_stats_trigger.sql (906.25µs)1111niks3-go-unit-tests> 2026/10/01 10:25:03 OK   3_commit_push.sql (560.04µs)1112niks3-go-unit-tests> 2026/10/01 10:25:03 goose: up to current file version: 31113niks3-go-unit-tests> 2026/10/01 10:25:03 INFO Received uploads request method=POST path=/api/pending_closures1114niks3-go-unit-tests> 2026/10/01 10:25:03 INFO Received uploads request method=POST path=/api/pending_closures1115niks3-go-unit-tests> 2026/10/01 10:25:03 INFO Received uploads request method=POST path=/api/pending_closures1116niks3-go-unit-tests> 2026/10/01 10:25:03 OK   20241026095416_initial_model.sql (423.65ms)1117niks3-go-unit-tests> 2026/10/01 10:25:04 OK   20251210153512_drop_unused_gin_index.sql (21.26ms)1118niks3-go-unit-tests> 2026/10/01 10:25:04 OK   20251218171726_add_pins.sql (87.72ms)1119niks3-go-unit-tests> 2026/10/01 10:25:04 OK   20260628120000_add_object_size_and_stats.sql (63.31ms)1120niks3-go-unit-tests> 2026/10/01 10:25:04 INFO Received uploads request method=POST path=/api/pending_closures1121niks3-go-unit-tests> 2026/10/01 10:25:04 OK   20260905000000_add_claims.sql (192.72ms)1122niks3-go-unit-tests> 2026/10/01 10:25:04 OK   20260920000000_drop_claims.sql (112.29ms)1123niks3-go-unit-tests> 2026/10/01 10:25:04 OK   20260923120000_add_pushes.sql (31.37ms)1124niks3-go-unit-tests> 2026/10/01 10:25:04 goose: successfully migrated database to version: 202609231200001125niks3-go-unit-tests> 2026/10/01 10:25:04 OK   1_commit_pending_closure.sql (2.32ms)1126niks3-go-unit-tests> 2026/10/01 10:25:04 OK   2_object_stats_trigger.sql (501.42µs)1127niks3-go-unit-tests> 2026/10/01 10:25:04 OK   3_commit_push.sql (405.08µs)1128niks3-go-unit-tests> 2026/10/01 10:25:04 goose: up to current file version: 31129niks3-go-unit-tests> 2026/10/01 10:25:04 INFO Received cleanup request method=DELETE path=/api/pending_closures1130niks3-go-unit-tests> 2026/10/01 10:25:04 INFO Aborted multipart uploads count=0 kept=01131niks3-go-unit-tests> 2026/10/01 10:25:04 INFO Received uploads request method=POST path=/api/pending_closures1132niks3-go-unit-tests> 2026/10/01 10:25:05 INFO Received cleanup request method=DELETE path=/api/pending_closures1133niks3-go-unit-tests> 2026/10/01 10:25:05 INFO Aborted multipart uploads count=1 kept=01134niks3-go-unit-tests> 2026/10/01 10:25:05 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1135niks3-go-unit-tests> 2026-10-01 10:25:05.105 UTC [42167] ERROR:  Closure does not exist: id=11136niks3-go-unit-tests> 2026-10-01 10:25:05.105 UTC [42167] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 12 at RAISE1137niks3-go-unit-tests> 2026-10-01 10:25:05.105 UTC [42167] STATEMENT:  -- name: CommitPendingClosure :exec1138niks3-go-unit-tests> 	SELECT commit_pending_closure($1::bigint)1139niks3-go-unit-tests> 	1140niks3-go-unit-tests> --- PASS: TestService_cleanupPendingClosuresHandler (6.27s)1141niks3-go-unit-tests> === CONT  TestLeadEndsOnShutdown1142niks3-go-unit-tests> 2026/10/01 10:25:05 INFO Received uploads request method=POST path=/api/pending_closures1143niks3-go-unit-tests> 2026-10-01 10:25:05.742 UTC [42185] ERROR:  relation "goose_db_version" does not exist at character 361144niks3-go-unit-tests> 2026-10-01 10:25:05.742 UTC [42185] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1145niks3-go-unit-tests> === RUN   TestPush_RejectsBadRequests/root_not_in_objects1146niks3-go-unit-tests> === PAUSE TestPush_RejectsBadRequests/root_not_in_objects1147niks3-go-unit-tests> === RUN   TestPush_RejectsBadRequests/no_roots1148niks3-go-unit-tests> === PAUSE TestPush_RejectsBadRequests/no_roots1149niks3-go-unit-tests> === RUN   TestPush_RejectsBadRequests/no_objects1150niks3-go-unit-tests> === PAUSE TestPush_RejectsBadRequests/no_objects1151niks3-go-unit-tests> === RUN   TestPush_RejectsBadRequests/bad_root1152niks3-go-unit-tests> === PAUSE TestPush_RejectsBadRequests/bad_root1153niks3-go-unit-tests> === CONT  TestLeadElectsOneAndHandsOver1154niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1155niks3-go-unit-tests> 2026-10-01 10:25:06.461 UTC [42190] ERROR:  relation "goose_db_version" does not exist at character 361156niks3-go-unit-tests> 2026-10-01 10:25:06.461 UTC [42190] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1157niks3-go-unit-tests> 2026/10/01 10:25:06 OK   20241026095416_initial_model.sql (629.69ms)1158niks3-go-unit-tests> 2026/10/01 10:25:06 OK   20251210153512_drop_unused_gin_index.sql (27.96ms)1159niks3-go-unit-tests> 2026/10/01 10:25:06 OK   20251218171726_add_pins.sql (76.54ms)1160niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1161niks3-go-unit-tests> 2026-10-01 10:25:06.682 UTC [42191] ERROR:  relation "goose_db_version" does not exist at character 361162niks3-go-unit-tests> 2026-10-01 10:25:06.682 UTC [42191] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1163niks3-go-unit-tests> 2026/10/01 10:25:06 OK   20260628120000_add_object_size_and_stats.sql (36.17ms)1164niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=NGQ5MGM3NzItZmU4NC00NjdiLWI1NjgtZTY0ZTg0MTg2ZTA5LjczM2U0Y2Q3LWJkZTUtNDNmOC05MDEwLWVlZTkzNGE4OGFhYngxNzkwODUwMzAzNzk5ODQzMDAw parts=101165niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1166niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Completed upload id=11167niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001168niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Received uploads request method=POST path=/api/pending_closures1169niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Starting cleanup of old closures method=DELETE path=/api/closures1170niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Aborted multipart uploads count=0 kept=01171niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=01172niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Vacuumed table table=pending_closures1173niks3-go-unit-tests> 2026/10/01 10:25:06 OK   20260905000000_add_claims.sql (183.25ms)1174niks3-go-unit-tests> 2026/10/01 10:25:06 OK   20260920000000_drop_claims.sql (57.32ms)1175niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Vacuumed table table=pending_objects1176niks3-go-unit-tests> 2026/10/01 10:25:06 OK   20260923120000_add_pushes.sql (49.35ms)1177niks3-go-unit-tests> 2026/10/01 10:25:06 goose: successfully migrated database to version: 202609231200001178niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Vacuumed table table=multipart_uploads1179niks3-go-unit-tests> 2026/10/01 10:25:06 OK   1_commit_pending_closure.sql (2.95ms)1180niks3-go-unit-tests> 2026/10/01 10:25:06 OK   2_object_stats_trigger.sql (609.21µs)1181niks3-go-unit-tests> 2026/10/01 10:25:06 OK   3_commit_push.sql (394.75µs)1182niks3-go-unit-tests> 2026/10/01 10:25:06 goose: up to current file version: 31183niks3-go-unit-tests> 2026/10/01 10:25:06 OK   20241026095416_initial_model.sql (395.19ms)1184niks3-go-unit-tests> 2026/10/01 10:25:06 INFO Vacuumed table table=closures1185niks3-go-unit-tests> 2026-10-01 10:25:06.992 UTC [42193] ERROR:  relation "goose_db_version" does not exist at character 361186niks3-go-unit-tests> 2026-10-01 10:25:06.992 UTC [42193] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1187niks3-go-unit-tests> 2026/10/01 10:25:06 OK   20251210153512_drop_unused_gin_index.sql (5.79ms)1188niks3-go-unit-tests> 2026/10/01 10:25:07 INFO Vacuumed table table=objects1189niks3-go-unit-tests> 2026/10/01 10:25:07 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001190niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20251218171726_add_pins.sql (31.68ms)1191niks3-go-unit-tests> --- PASS: TestService_createPendingClosureHandler (8.39s)1192niks3-go-unit-tests> === CONT  TestResolveDBConnectionString1193niks3-go-unit-tests> === RUN   TestResolveDBConnectionString/flag_wins1194niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString/flag_wins1195niks3-go-unit-tests> === RUN   TestResolveDBConnectionString/file_when_flag_empty1196niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString/file_when_flag_empty1197niks3-go-unit-tests> === RUN   TestResolveDBConnectionString/missing_file_is_an_error1198niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString/missing_file_is_an_error1199niks3-go-unit-tests> === RUN   TestResolveDBConnectionString/PGHOST_allows_empty1200niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString/PGHOST_allows_empty1201niks3-go-unit-tests> === RUN   TestResolveDBConnectionString/nothing_configured1202niks3-go-unit-tests> === PAUSE TestResolveDBConnectionString/nothing_configured1203niks3-go-unit-tests> === CONT  TestConcurrentCommitsSharingObjectsDoNotDeadlock1204niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20241026095416_initial_model.sql (225.28ms)1205niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20251210153512_drop_unused_gin_index.sql (17.56ms)1206niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20260628120000_add_object_size_and_stats.sql (60.75ms)1207niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20251218171726_add_pins.sql (69.83ms)1208niks3-go-unit-tests> 2026/10/01 10:25:07 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1209niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20260628120000_add_object_size_and_stats.sql (54.59ms)1210niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20260905000000_add_claims.sql (124.49ms)1211niks3-go-unit-tests> 2026/10/01 10:25:07 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=NGQ5MGM3NzItZmU4NC00NjdiLWI1NjgtZTY0ZTg0MTg2ZTA5LjQ4ODk1ZDBjLTM4ZDEtNDg2NC05MGY4LTM5NjQ5NGZmYzAwZHgxNzkwODUwMzA0MzY4MTM0MDAw parts=101212niks3-go-unit-tests> 2026/10/01 10:25:07 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1213niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20260920000000_drop_claims.sql (61.15ms)1214niks3-go-unit-tests> 2026/10/01 10:25:07 INFO Completed upload id=11215niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20260905000000_add_claims.sql (85.51ms)1216niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20260923120000_add_pushes.sql (15.19ms)1217niks3-go-unit-tests> 2026/10/01 10:25:07 goose: successfully migrated database to version: 202609231200001218niks3-go-unit-tests> 2026/10/01 10:25:07 INFO Received uploads request method=POST path=/api/pending_closures1219niks3-go-unit-tests> 2026/10/01 10:25:07 INFO Received uploads request method=POST path=/api/pending_closures1220niks3-go-unit-tests> 2026/10/01 10:25:07 OK   1_commit_pending_closure.sql (2.65ms)1221niks3-go-unit-tests> 2026/10/01 10:25:07 OK   2_object_stats_trigger.sql (526.25µs)1222niks3-go-unit-tests> 2026/10/01 10:25:07 OK   3_commit_push.sql (441.17µs)1223niks3-go-unit-tests> 2026/10/01 10:25:07 goose: up to current file version: 31224niks3-go-unit-tests> 2026/10/01 10:25:07 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo1225niks3-go-unit-tests> 2026/10/01 10:25:07 WARN Found objects in DB but missing from S3, will re-upload count=11226niks3-go-unit-tests> --- PASS: TestService_verifyS3Integrity (8.69s)1227niks3-go-unit-tests> === CONT  TestClientFallsBackToClosures1228niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20260920000000_drop_claims.sql (73.68ms)1229niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20260923120000_add_pushes.sql (29.75ms)1230niks3-go-unit-tests> 2026/10/01 10:25:07 goose: successfully migrated database to version: 202609231200001231niks3-go-unit-tests> 2026/10/01 10:25:07 OK   1_commit_pending_closure.sql (2.92ms)1232niks3-go-unit-tests> 2026/10/01 10:25:07 OK   2_object_stats_trigger.sql (373.25µs)1233niks3-go-unit-tests> 2026/10/01 10:25:07 OK   3_commit_push.sql (289.71µs)1234niks3-go-unit-tests> 2026/10/01 10:25:07 goose: up to current file version: 31235niks3-go-unit-tests> --- PASS: TestReadRedirectUsesPublicS3URL (7.56s)1236niks3-go-unit-tests> === CONT  TestClientPushesUseOnePush1237niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20241026095416_initial_model.sql (355.91ms)1238niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20251210153512_drop_unused_gin_index.sql (15.62ms)1239niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20251218171726_add_pins.sql (41.66ms)1240niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20260628120000_add_object_size_and_stats.sql (72.22ms)1241niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20260905000000_add_claims.sql (96.66ms)1242niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20260920000000_drop_claims.sql (18.97ms)1243niks3-go-unit-tests> 2026/10/01 10:25:07 OK   20260923120000_add_pushes.sql (16.13ms)1244niks3-go-unit-tests> 2026/10/01 10:25:07 goose: successfully migrated database to version: 202609231200001245niks3-go-unit-tests> 2026/10/01 10:25:07 OK   1_commit_pending_closure.sql (2.7ms)1246niks3-go-unit-tests> 2026/10/01 10:25:07 OK   2_object_stats_trigger.sql (539.04µs)1247niks3-go-unit-tests> 2026/10/01 10:25:07 OK   3_commit_push.sql (328.88µs)1248niks3-go-unit-tests> 2026/10/01 10:25:07 goose: up to current file version: 31249niks3-go-unit-tests> 2026/10/01 10:25:07 INFO Received push request method=POST path=/api/pushes1250niks3-go-unit-tests> --- PASS: TestPush_OverlappingRootsStoreOneRowPerKey (7.41s)1251niks3-go-unit-tests> === CONT  TestPinProtectsFromGC1252niks3-go-unit-tests> 2026-10-01 10:25:07.963 UTC [42206] ERROR:  relation "goose_db_version" does not exist at character 361253niks3-go-unit-tests> 2026-10-01 10:25:07.963 UTC [42206] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1254niks3-go-unit-tests> 2026/10/01 10:25:08 OK   20241026095416_initial_model.sql (319.94ms)1255niks3-go-unit-tests> 2026/10/01 10:25:08 OK   20251210153512_drop_unused_gin_index.sql (28.28ms)1256niks3-go-unit-tests> 2026/10/01 10:25:08 OK   20251218171726_add_pins.sql (59.71ms)1257niks3-go-unit-tests> 2026/10/01 10:25:08 OK   20260628120000_add_object_size_and_stats.sql (77.18ms)1258niks3-go-unit-tests> 2026/10/01 10:25:08 OK   20260905000000_add_claims.sql (138.06ms)1259niks3-go-unit-tests> 2026/10/01 10:25:08 INFO Aborted multipart uploads count=0 kept=01260niks3-go-unit-tests> 2026/10/01 10:25:08 WARN Force mode enabled - objects will be deleted immediately without grace period1261niks3-go-unit-tests> 2026/10/01 10:25:08 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=01262niks3-go-unit-tests> 2026/10/01 10:25:08 INFO Vacuumed table table=pending_closures1263niks3-go-unit-tests> 2026/10/01 10:25:08 INFO Vacuumed table table=pending_objects1264niks3-go-unit-tests> 2026/10/01 10:25:08 INFO Vacuumed table table=multipart_uploads1265niks3-go-unit-tests> 2026/10/01 10:25:08 INFO Vacuumed table table=closures1266niks3-go-unit-tests> 2026/10/01 10:25:08 INFO Vacuumed table table=objects1267niks3-go-unit-tests> --- PASS: TestGCMetrics (7.19s)1268niks3-go-unit-tests> === CONT  TestClientSharedPathCommittedMidPush1269niks3-go-unit-tests> 2026/10/01 10:25:08 OK   20260920000000_drop_claims.sql (230.24ms)1270niks3-go-unit-tests> === NAME  TestClientMultipleUploads1271niks3-go-unit-tests>     client_integration_test.go:358: Created store path 0: /nix/var/nix/builds/nix-41517-960550241/TestClientMultipleUploads1355300712/001/store/0awkl9wkj6b6bqb9yd0swcwwi9lh2j7x-test-file-0.txt1272niks3-go-unit-tests> 2026/10/01 10:25:08 OK   20260923120000_add_pushes.sql (29.42ms)1273niks3-go-unit-tests> 2026/10/01 10:25:08 goose: successfully migrated database to version: 202609231200001274niks3-go-unit-tests> 2026/10/01 10:25:08 OK   1_commit_pending_closure.sql (2.8ms)1275niks3-go-unit-tests> 2026/10/01 10:25:08 OK   2_object_stats_trigger.sql (655.25µs)1276niks3-go-unit-tests> 2026/10/01 10:25:08 OK   3_commit_push.sql (473.13µs)1277niks3-go-unit-tests> 2026/10/01 10:25:08 goose: up to current file version: 31278niks3-go-unit-tests>     client_integration_test.go:358: Created store path 1: /nix/var/nix/builds/nix-41517-960550241/TestClientMultipleUploads1355300712/001/store/zxqpl91zacjv1k2rgi422jcc058k04c4-test-file-1.txt1279niks3-go-unit-tests>     client_integration_test.go:358: Created store path 2: /nix/var/nix/builds/nix-41517-960550241/TestClientMultipleUploads1355300712/001/store/6620mx0aziikn7mmgn67lzanppdkl4la-test-file-2.txt1280niks3-go-unit-tests> 2026/10/01 10:25:09 INFO Received push request method=POST path=/api/pushes1281niks3-go-unit-tests> 2026/10/01 10:25:09 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)1282niks3-go-unit-tests> 2026/10/01 10:25:09 INFO Uploading 6620mx0aziikn7mmgn67lzanppdkl4la-test-file-2.txt (160B)1283niks3-go-unit-tests> 2026/10/01 10:25:09 INFO Uploading 0awkl9wkj6b6bqb9yd0swcwwi9lh2j7x-test-file-0.txt (160B)1284niks3-go-unit-tests> 2026/10/01 10:25:09 INFO Uploading zxqpl91zacjv1k2rgi422jcc058k04c4-test-file-1.txt (160B)1285niks3-go-unit-tests> 2026/10/01 10:25:09 WARN Failed to register uploaded object key=nar/1823g6vx4q5zqvr73m7lkq1wpk40fin7bsc792di430yk7sg4lka.nar.zst error="server returned 404: 404 page not found\n"1286niks3-go-unit-tests> 2026/10/01 10:25:09 WARN Failed to register uploaded object key=zxqpl91zacjv1k2rgi422jcc058k04c4.ls error="server returned 404: 404 page not found\n"1287niks3-go-unit-tests> 2026/10/01 10:25:09 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1288niks3-go-unit-tests> 2026/10/01 10:25:09 WARN Failed to register uploaded object key=nar/0ahq3izw3h0b0c08p3xdfclh758fwxnp85ccvflasb4vnfsv50ri.nar.zst error="server returned 404: 404 page not found\n"1289niks3-go-unit-tests> 2026/10/01 10:25:09 WARN Failed to register uploaded object key=6620mx0aziikn7mmgn67lzanppdkl4la.ls error="server returned 404: 404 page not found\n"1290niks3-go-unit-tests> 2026/10/01 10:25:09 WARN Failed to register uploaded object key=nar/13p9pqi0ga6fx2apcwls9hjnv7nmf740ggpn5zvfzdsp5vz2bbg4.nar.zst error="server returned 404: 404 page not found\n"1291niks3-go-unit-tests> 2026/10/01 10:25:09 WARN Failed to register uploaded object key=0awkl9wkj6b6bqb9yd0swcwwi9lh2j7x.ls error="server returned 404: 404 page not found\n"1292niks3-go-unit-tests> 2026/10/01 10:25:09 INFO Signed narinfos id=1 count=31293niks3-go-unit-tests> 2026/10/01 10:25:09 INFO Uploading 3 narinfos1294niks3-go-unit-tests> 2026/10/01 10:25:09 WARN Failed to register uploaded object key=6620mx0aziikn7mmgn67lzanppdkl4la.narinfo error="server returned 404: 404 page not found\n"1295niks3-go-unit-tests> 2026/10/01 10:25:09 INFO Received complete push request method=POST path=/api/pushes/1/complete1296niks3-go-unit-tests> 2026/10/01 10:25:09 WARN Failed to register uploaded object key=zxqpl91zacjv1k2rgi422jcc058k04c4.narinfo error="server returned 404: 404 page not found\n"1297niks3-go-unit-tests> 2026/10/01 10:25:09 WARN Failed to register uploaded object key=0awkl9wkj6b6bqb9yd0swcwwi9lh2j7x.narinfo error="server returned 404: 404 page not found\n"1298niks3-go-unit-tests> 2026-10-01 10:25:09.651 UTC [42228] ERROR:  relation "goose_db_version" does not exist at character 361299niks3-go-unit-tests> 2026-10-01 10:25:09.651 UTC [42228] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1300niks3-go-unit-tests> 2026/10/01 10:25:09 INFO Upload complete. (242ms)1301niks3-go-unit-tests>     client_integration_test.go:369: Uploaded 3 paths in 277.747375ms1302niks3-go-unit-tests> --- PASS: TestGCBugBareHashReferences (6.54s)1303niks3-go-unit-tests> === CONT  TestClientWithDependencies1304niks3-go-unit-tests> --- PASS: TestClientMultipleUploads (8.95s)1305niks3-go-unit-tests> === CONT  TestPush_SkippedKeySurvivesGCBeforeCommit1306niks3-go-unit-tests> 2026/10/01 10:25:10 WARN Rate limiter enabled after throttle name=s3-test rate=51307niks3-go-unit-tests> 2026/10/01 10:25:10 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1308niks3-go-unit-tests> === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1309niks3-go-unit-tests>     throttle_test.go:213: Proxy stats: total=13, throttled=10, completeMultipart=101310niks3-go-unit-tests>     throttle_test.go:215: Rate limiter: enabled=true, rate=5.001311niks3-go-unit-tests> === CONT  TestCompleteMultipartUpload_ErrorButObjectExists1312niks3-go-unit-tests> --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (10.92s)1313niks3-go-unit-tests> 2026/10/01 10:25:10 OK   20241026095416_initial_model.sql (278.67ms)1314niks3-go-unit-tests> 2026/10/01 10:25:10 OK   20251210153512_drop_unused_gin_index.sql (24.45ms)1315niks3-go-unit-tests> 2026/10/01 10:25:10 OK   20251218171726_add_pins.sql (45.01ms)1316niks3-go-unit-tests> 2026/10/01 10:25:10 OK   20260628120000_add_object_size_and_stats.sql (60.23ms)1317niks3-go-unit-tests> 2026-10-01 10:25:10.248 UTC [42240] ERROR:  relation "goose_db_version" does not exist at character 361318niks3-go-unit-tests> 2026-10-01 10:25:10.248 UTC [42240] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1319niks3-go-unit-tests> 2026/10/01 10:25:10 OK   20260905000000_add_claims.sql (139.11ms)1320niks3-go-unit-tests> 2026/10/01 10:25:10 OK   20260920000000_drop_claims.sql (44.51ms)1321niks3-go-unit-tests> 2026/10/01 10:25:10 OK   20260923120000_add_pushes.sql (55.89ms)1322niks3-go-unit-tests> 2026/10/01 10:25:10 goose: successfully migrated database to version: 202609231200001323niks3-go-unit-tests> 2026/10/01 10:25:10 OK   1_commit_pending_closure.sql (3.09ms)1324niks3-go-unit-tests> 2026/10/01 10:25:10 OK   2_object_stats_trigger.sql (665.63µs)1325niks3-go-unit-tests> 2026/10/01 10:25:10 OK   3_commit_push.sql (386.58µs)1326niks3-go-unit-tests> 2026/10/01 10:25:10 goose: up to current file version: 31327niks3-go-unit-tests> 2026/10/01 10:25:10 OK   20241026095416_initial_model.sql (325.45ms)1328niks3-go-unit-tests> 2026/10/01 10:25:10 OK   20251210153512_drop_unused_gin_index.sql (19.55ms)1329niks3-go-unit-tests> 2026/10/01 10:25:10 OK   20251218171726_add_pins.sql (60.2ms)1330niks3-go-unit-tests> 2026/10/01 10:25:10 OK   20260628120000_add_object_size_and_stats.sql (84.25ms)1331niks3-go-unit-tests> 2026/10/01 10:25:10 INFO lead: acquired remote=192.0.2.1:12341332niks3-go-unit-tests> 2026/10/01 10:25:10 INFO lead: released remote=192.0.2.1:12341333niks3-go-unit-tests> --- PASS: TestLeadEndsOnShutdown (5.77s)1334niks3-go-unit-tests> === CONT  TestService_Rustfstest1335niks3-go-unit-tests> 2026/10/01 10:25:10 OK   20260905000000_add_claims.sql (128.3ms)1336niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20260920000000_drop_claims.sql (37.53ms)1337niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20260923120000_add_pushes.sql (13.16ms)1338niks3-go-unit-tests> 2026/10/01 10:25:11 goose: successfully migrated database to version: 202609231200001339niks3-go-unit-tests> 2026/10/01 10:25:11 OK   1_commit_pending_closure.sql (2.02ms)1340niks3-go-unit-tests> 2026/10/01 10:25:11 OK   2_object_stats_trigger.sql (453.67µs)1341niks3-go-unit-tests> 2026/10/01 10:25:11 OK   3_commit_push.sql (400.33µs)1342niks3-go-unit-tests> 2026/10/01 10:25:11 goose: up to current file version: 31343niks3-go-unit-tests> 2026-10-01 10:25:11.334 UTC [42254] ERROR:  relation "goose_db_version" does not exist at character 361344niks3-go-unit-tests> 2026-10-01 10:25:11.334 UTC [42254] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1345niks3-go-unit-tests> 2026/10/01 10:25:11 INFO lead: acquired remote=192.0.2.1:12341346niks3-go-unit-tests> 2026-10-01 10:25:11.531 UTC [42258] ERROR:  relation "goose_db_version" does not exist at character 361347niks3-go-unit-tests> 2026-10-01 10:25:11.531 UTC [42258] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1348niks3-go-unit-tests> 2026/10/01 10:25:11 INFO lead: released remote=192.0.2.1:12341349niks3-go-unit-tests> 2026-10-01 10:25:11.599 UTC [42260] ERROR:  relation "goose_db_version" does not exist at character 361350niks3-go-unit-tests> 2026-10-01 10:25:11.599 UTC [42260] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1351niks3-go-unit-tests> 2026/10/01 10:25:11 INFO lead: acquired remote=192.0.2.1:12341352niks3-go-unit-tests> 2026/10/01 10:25:11 INFO lead: released remote=192.0.2.1:12341353niks3-go-unit-tests> --- PASS: TestLeadElectsOneAndHandsOver (5.51s)1354niks3-go-unit-tests> === CONT  TestGCTaskStore_DeduplicateSameParams1355niks3-go-unit-tests> --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)1356niks3-go-unit-tests> === CONT  TestCreatePendingClosureRejectsOversizedNAR1357niks3-go-unit-tests> 2026/10/01 10:25:11 INFO Received uploads request method=POST path=/api/pending_closures1358niks3-go-unit-tests> --- PASS: TestCreatePendingClosureRejectsOversizedNAR (0.00s)1359niks3-go-unit-tests> === CONT  TestCacheConfigHandlerMaxNarSize1360niks3-go-unit-tests> --- PASS: TestCacheConfigHandlerMaxNarSize (0.00s)1361niks3-go-unit-tests> === CONT  TestGenerateLandingPage1362niks3-go-unit-tests> --- PASS: TestGenerateLandingPage (0.00s)1363niks3-go-unit-tests> === CONT  TestService_readinessHandler1364niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20241026095416_initial_model.sql (266.75ms)1365niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20251210153512_drop_unused_gin_index.sql (9.83ms)1366niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20251218171726_add_pins.sql (38.25ms)1367niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20260628120000_add_object_size_and_stats.sql (59.21ms)1368niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20241026095416_initial_model.sql (244.14ms)1369niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20251210153512_drop_unused_gin_index.sql (15.17ms)1370niks3-go-unit-tests> 2026-10-01 10:25:11.870 UTC [42268] ERROR:  relation "goose_db_version" does not exist at character 361371niks3-go-unit-tests> 2026-10-01 10:25:11.870 UTC [42268] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1372niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20251218171726_add_pins.sql (5.51ms)1373niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20260905000000_add_claims.sql (92.78ms)1374niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20241026095416_initial_model.sql (248.31ms)1375niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20260628120000_add_object_size_and_stats.sql (47.22ms)1376niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20251210153512_drop_unused_gin_index.sql (22.94ms)1377niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20260920000000_drop_claims.sql (49.48ms)1378niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20260923120000_add_pushes.sql (16.93ms)1379niks3-go-unit-tests> 2026/10/01 10:25:11 goose: successfully migrated database to version: 202609231200001380niks3-go-unit-tests> 2026/10/01 10:25:11 OK   1_commit_pending_closure.sql (1.42ms)1381niks3-go-unit-tests> 2026/10/01 10:25:11 OK   2_object_stats_trigger.sql (242.46µs)1382niks3-go-unit-tests> 2026/10/01 10:25:11 OK   3_commit_push.sql (200.08µs)1383niks3-go-unit-tests> 2026/10/01 10:25:11 goose: up to current file version: 31384niks3-go-unit-tests> 2026/10/01 10:25:11 OK   20251218171726_add_pins.sql (34.56ms)1385niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20260905000000_add_claims.sql (85.81ms)1386niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20260920000000_drop_claims.sql (12.95ms)1387niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20260628120000_add_object_size_and_stats.sql (51.7ms)1388niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20260923120000_add_pushes.sql (16.42ms)1389niks3-go-unit-tests> 2026/10/01 10:25:12 goose: successfully migrated database to version: 202609231200001390niks3-go-unit-tests> 2026/10/01 10:25:12 OK   1_commit_pending_closure.sql (1.52ms)1391niks3-go-unit-tests> 2026/10/01 10:25:12 OK   2_object_stats_trigger.sql (241.58µs)1392niks3-go-unit-tests> 2026/10/01 10:25:12 OK   3_commit_push.sql (215.79µs)1393niks3-go-unit-tests> 2026/10/01 10:25:12 goose: up to current file version: 31394niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20260905000000_add_claims.sql (37.75ms)1395niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20260920000000_drop_claims.sql (63.69ms)1396niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20260923120000_add_pushes.sql (21.37ms)1397niks3-go-unit-tests> 2026/10/01 10:25:12 goose: successfully migrated database to version: 202609231200001398niks3-go-unit-tests> 2026/10/01 10:25:12 OK   1_commit_pending_closure.sql (1.12ms)1399niks3-go-unit-tests> 2026/10/01 10:25:12 OK   2_object_stats_trigger.sql (230.46µs)1400niks3-go-unit-tests> 2026/10/01 10:25:12 OK   3_commit_push.sql (179.63µs)1401niks3-go-unit-tests> 2026/10/01 10:25:12 goose: up to current file version: 31402niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20241026095416_initial_model.sql (275.94ms)1403niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20251210153512_drop_unused_gin_index.sql (20.8ms)1404niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20251218171726_add_pins.sql (53.28ms)1405niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20260628120000_add_object_size_and_stats.sql (59.64ms)1406niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20260905000000_add_claims.sql (228.69ms)1407niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20260920000000_drop_claims.sql (87.76ms)1408niks3-go-unit-tests> 2026/10/01 10:25:12 OK   20260923120000_add_pushes.sql (39.02ms)1409niks3-go-unit-tests> 2026/10/01 10:25:12 goose: successfully migrated database to version: 202609231200001410niks3-go-unit-tests> 2026/10/01 10:25:12 OK   1_commit_pending_closure.sql (4.51ms)1411niks3-go-unit-tests> 2026/10/01 10:25:12 OK   2_object_stats_trigger.sql (2.81ms)1412niks3-go-unit-tests> 2026/10/01 10:25:12 OK   3_commit_push.sql (1.18ms)1413niks3-go-unit-tests> 2026/10/01 10:25:12 goose: up to current file version: 31414niks3-go-unit-tests> 2026-10-01 10:25:12.771 UTC [42275] ERROR:  relation "goose_db_version" does not exist at character 361415niks3-go-unit-tests> 2026-10-01 10:25:12.771 UTC [42275] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1416niks3-go-unit-tests> 2026/10/01 10:25:13 OK   20241026095416_initial_model.sql (327.13ms)1417niks3-go-unit-tests> 2026/10/01 10:25:13 OK   20251210153512_drop_unused_gin_index.sql (9.78ms)1418niks3-go-unit-tests> 2026/10/01 10:25:13 OK   20251218171726_add_pins.sql (65.45ms)1419niks3-go-unit-tests> 2026/10/01 10:25:13 OK   20260628120000_add_object_size_and_stats.sql (61.03ms)1420niks3-go-unit-tests> 2026/10/01 10:25:13 OK   20260905000000_add_claims.sql (94.12ms)1421niks3-go-unit-tests> 2026/10/01 10:25:13 OK   20260920000000_drop_claims.sql (54.12ms)1422niks3-go-unit-tests> 2026/10/01 10:25:13 OK   20260923120000_add_pushes.sql (29.17ms)1423niks3-go-unit-tests> 2026/10/01 10:25:13 goose: successfully migrated database to version: 202609231200001424niks3-go-unit-tests> 2026/10/01 10:25:13 OK   1_commit_pending_closure.sql (949.29µs)1425niks3-go-unit-tests> 2026/10/01 10:25:13 OK   2_object_stats_trigger.sql (234.38µs)1426niks3-go-unit-tests> 2026/10/01 10:25:13 OK   3_commit_push.sql (193.38µs)1427niks3-go-unit-tests> 2026/10/01 10:25:13 goose: up to current file version: 31428niks3-go-unit-tests> 2026-10-01 10:25:13.568 UTC [42284] ERROR:  relation "goose_db_version" does not exist at character 361429niks3-go-unit-tests> 2026-10-01 10:25:13.568 UTC [42284] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1430niks3-go-unit-tests> 2026-10-01 10:25:13.781 UTC [42291] ERROR:  relation "goose_db_version" does not exist at character 361431niks3-go-unit-tests> 2026-10-01 10:25:13.781 UTC [42291] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1432niks3-go-unit-tests> 2026-10-01 10:25:13.852 UTC [42295] ERROR:  relation "goose_db_version" does not exist at character 361433niks3-go-unit-tests> 2026-10-01 10:25:13.852 UTC [42295] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1434niks3-go-unit-tests> 2026/10/01 10:25:13 INFO Received uploads request method=POST path=/api/pending_closures1435niks3-go-unit-tests> 2026/10/01 10:25:13 INFO Received uploads request method=POST path=/api/pending_closures1436niks3-go-unit-tests> 2026/10/01 10:25:13 INFO Uploading 2 paths to 127.0.0.1 (1 already cached)1437niks3-go-unit-tests> 2026/10/01 10:25:13 INFO Uploading 7zvq2wf3q4iqrnbz0wr3k92m19i1x23r-shared-dep (136B)1438niks3-go-unit-tests> 2026/10/01 10:25:13 INFO Uploading wz95sp930kcdn645hzfm4fkk5vyjqk7l-b (248B)1439niks3-go-unit-tests> 2026/10/01 10:25:13 OK   20241026095416_initial_model.sql (314.31ms)1440niks3-go-unit-tests> 2026/10/01 10:25:13 WARN Failed to register uploaded object key=7zvq2wf3q4iqrnbz0wr3k92m19i1x23r.ls error="server returned 404: 404 page not found\n"1441niks3-go-unit-tests> 2026/10/01 10:25:13 OK   20251210153512_drop_unused_gin_index.sql (8.34ms)1442niks3-go-unit-tests> 2026/10/01 10:25:13 WARN Failed to register uploaded object key=wz95sp930kcdn645hzfm4fkk5vyjqk7l.ls error="server returned 404: 404 page not found\n"1443niks3-go-unit-tests> 2026/10/01 10:25:13 WARN Failed to register uploaded object key=4zq9r151jbbv5zajlk4v39illf5jcrwg.ls error="server returned 404: 404 page not found\n"1444niks3-go-unit-tests> 2026/10/01 10:25:13 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1445niks3-go-unit-tests> 2026/10/01 10:25:13 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"1446niks3-go-unit-tests> 2026/10/01 10:25:13 WARN Failed to register uploaded object key=nar/0niycamirsnzws2q5jkw0ki0n0asa3m2241qvg5lxxq9fhhzv0nm.nar.zst error="server returned 404: 404 page not found\n"1447niks3-go-unit-tests> 2026/10/01 10:25:13 INFO Signed narinfos id=1 count=21448niks3-go-unit-tests> 2026/10/01 10:25:13 INFO Received push request method=POST path=/api/pushes1449niks3-go-unit-tests> 2026/10/01 10:25:13 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign1450niks3-go-unit-tests> 2026/10/01 10:25:13 INFO Signed narinfos id=2 count=21451niks3-go-unit-tests> 2026/10/01 10:25:13 INFO Uploading 4 narinfos1452niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=wz95sp930kcdn645hzfm4fkk5vyjqk7l.narinfo error="server returned 404: 404 page not found\n"1453niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=4zq9r151jbbv5zajlk4v39illf5jcrwg.narinfo error="server returned 404: 404 page not found\n"1454niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20251218171726_add_pins.sql (38.66ms)1455niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading 2 paths to 127.0.0.1 (1 already cached)1456niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading 17hfcryi4csm0ms6mg9ir67v3x71gwgs-a (248B)1457niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=7zvq2wf3q4iqrnbz0wr3k92m19i1x23r.narinfo error="server returned 404: 404 page not found\n"1458niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading y7hn337vx3czal0bz9cajvfvhdrzmbjk-shared-dep (136B)1459niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=17hfcryi4csm0ms6mg9ir67v3x71gwgs.ls error="server returned 404: 404 page not found\n"1460niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"1461niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1462niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=7zvq2wf3q4iqrnbz0wr3k92m19i1x23r.narinfo error="server returned 404: 404 page not found\n"1463niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=1r7zf2nv4w8y4xhm7i3ma77h94955ln0.ls error="server returned 404: 404 page not found\n"1464niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1465niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=nar/00mhv7vz3blx17a2m6jwqr62s4wrmbqgprfxa2gwhv5v1r5mciwn.nar.zst error="server returned 404: 404 page not found\n"1466niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=y7hn337vx3czal0bz9cajvfvhdrzmbjk.ls error="server returned 404: 404 page not found\n"1467niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Signed narinfos id=1 count=31468niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading 3 narinfos1469niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260628120000_add_object_size_and_stats.sql (43.64ms)1470niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1471niks3-go-unit-tests>     client_integration_test.go:731: Pinned store path: /nix/var/nix/builds/nix-41517-960550241/TestPinProtectsFromGC3268778793/001/store/lb2x2ab399zp2pgpyr6jig8ma3wpnnpa-pinned-file.txt1472niks3-go-unit-tests>     client_integration_test.go:732: Unpinned store path: /nix/var/nix/builds/nix-41517-960550241/TestPinProtectsFromGC3268778793/001/store/7kly396rygy4hflpybk3m4gfw2845c5x-unpinned-file.txt1473niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Received complete push request method=POST path=/api/pushes/1/complete1474niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=1r7zf2nv4w8y4xhm7i3ma77h94955ln0.narinfo error="server returned 404: 404 page not found\n"1475niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=y7hn337vx3czal0bz9cajvfvhdrzmbjk.narinfo error="server returned 404: 404 page not found\n"1476niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=17hfcryi4csm0ms6mg9ir67v3x71gwgs.narinfo error="server returned 404: 404 page not found\n"1477niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Completed upload id=21478niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1479niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Completed upload id=11480niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Upload complete. (246ms)1481niks3-go-unit-tests> === NAME  TestClientFallsBackToClosures1482niks3-go-unit-tests>     client_pushes_test.go:112: Retrieved narinfo from S3:1483niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-41517-960550241/TestClientFallsBackToClosures2120491395/001/store/7zvq2wf3q4iqrnbz0wr3k92m19i1x23r-shared-dep1484niks3-go-unit-tests>         URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1485niks3-go-unit-tests>         Compression: zstd1486niks3-go-unit-tests>         NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821487niks3-go-unit-tests>         NarSize: 1361488niks3-go-unit-tests>         References: 1489niks3-go-unit-tests>         CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1490niks3-go-unit-tests>     client_pushes_test.go:112: Retrieved narinfo from S3:1491niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-41517-960550241/TestClientFallsBackToClosures2120491395/001/store/4zq9r151jbbv5zajlk4v39illf5jcrwg-a1492niks3-go-unit-tests>         URL: nar/0niycamirsnzws2q5jkw0ki0n0asa3m2241qvg5lxxq9fhhzv0nm.nar.zst1493niks3-go-unit-tests>         Compression: zstd1494niks3-go-unit-tests>         NarHash: sha256:0niycamirsnzws2q5jkw0ki0n0asa3m2241qvg5lxxq9fhhzv0nm1495niks3-go-unit-tests>         NarSize: 2481496niks3-go-unit-tests>         References: /nix/var/nix/builds/nix-41517-960550241/TestClientFallsBackToClosures2120491395/001/store/7zvq2wf3q4iqrnbz0wr3k92m19i1x23r-shared-dep1497niks3-go-unit-tests>         CA: text:sha256:13fbdsl282yb8w5izzn93m1gvqxrj5axs9d1hx5ghsy9havrgvry1498niks3-go-unit-tests>     client_pushes_test.go:112: Retrieved narinfo from S3:1499niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-41517-960550241/TestClientFallsBackToClosures2120491395/001/store/wz95sp930kcdn645hzfm4fkk5vyjqk7l-b1500niks3-go-unit-tests>         URL: nar/0niycamirsnzws2q5jkw0ki0n0asa3m2241qvg5lxxq9fhhzv0nm.nar.zst1501niks3-go-unit-tests>         Compression: zstd1502niks3-go-unit-tests>         NarHash: sha256:0niycamirsnzws2q5jkw0ki0n0asa3m2241qvg5lxxq9fhhzv0nm1503niks3-go-unit-tests>         NarSize: 2481504niks3-go-unit-tests>         References: /nix/var/nix/builds/nix-41517-960550241/TestClientFallsBackToClosures2120491395/001/store/7zvq2wf3q4iqrnbz0wr3k92m19i1x23r-shared-dep1505niks3-go-unit-tests>         CA: text:sha256:13fbdsl282yb8w5izzn93m1gvqxrj5axs9d1hx5ghsy9havrgvry1506niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20241026095416_initial_model.sql (215.35ms)1507niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20241026095416_initial_model.sql (170.09ms)1508niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Upload complete. (207ms)1509niks3-go-unit-tests> === NAME  TestClientPushesUseOnePush1510niks3-go-unit-tests>     client_pushes_test.go:97: Retrieved narinfo from S3:1511niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-41517-960550241/TestClientPushesUseOnePush3528199070/001/store/y7hn337vx3czal0bz9cajvfvhdrzmbjk-shared-dep1512niks3-go-unit-tests>         URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1513niks3-go-unit-tests>         Compression: zstd1514niks3-go-unit-tests>         NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821515niks3-go-unit-tests>         NarSize: 1361516niks3-go-unit-tests>         References: 1517niks3-go-unit-tests>         CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1518niks3-go-unit-tests>     client_pushes_test.go:97: Retrieved narinfo from S3:1519niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-41517-960550241/TestClientPushesUseOnePush3528199070/001/store/17hfcryi4csm0ms6mg9ir67v3x71gwgs-a1520niks3-go-unit-tests>         URL: nar/00mhv7vz3blx17a2m6jwqr62s4wrmbqgprfxa2gwhv5v1r5mciwn.nar.zst1521niks3-go-unit-tests>         Compression: zstd1522niks3-go-unit-tests>         NarHash: sha256:00mhv7vz3blx17a2m6jwqr62s4wrmbqgprfxa2gwhv5v1r5mciwn1523niks3-go-unit-tests>         NarSize: 2481524niks3-go-unit-tests>         References: /nix/var/nix/builds/nix-41517-960550241/TestClientPushesUseOnePush3528199070/001/store/y7hn337vx3czal0bz9cajvfvhdrzmbjk-shared-dep1525niks3-go-unit-tests>         CA: text:sha256:0h9hgidydcb438fkrq7avrr4xnx0d2dmwpqhgfl9z7q3ww89d0ay1526niks3-go-unit-tests>     client_pushes_test.go:97: Retrieved narinfo from S3:1527niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-41517-960550241/TestClientPushesUseOnePush3528199070/001/store/1r7zf2nv4w8y4xhm7i3ma77h94955ln0-b1528niks3-go-unit-tests>         URL: nar/00mhv7vz3blx17a2m6jwqr62s4wrmbqgprfxa2gwhv5v1r5mciwn.nar.zst1529niks3-go-unit-tests>         Compression: zstd1530niks3-go-unit-tests>         NarHash: sha256:00mhv7vz3blx17a2m6jwqr62s4wrmbqgprfxa2gwhv5v1r5mciwn1531niks3-go-unit-tests>         NarSize: 2481532niks3-go-unit-tests>         References: /nix/var/nix/builds/nix-41517-960550241/TestClientPushesUseOnePush3528199070/001/store/y7hn337vx3czal0bz9cajvfvhdrzmbjk-shared-dep1533niks3-go-unit-tests>         CA: text:sha256:0h9hgidydcb438fkrq7avrr4xnx0d2dmwpqhgfl9z7q3ww89d0ay1534niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20251210153512_drop_unused_gin_index.sql (15.35ms)1535niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20251210153512_drop_unused_gin_index.sql (8.82ms)1536niks3-go-unit-tests> 2026-10-01 10:25:14.115 UTC [42315] ERROR:  relation "goose_db_version" does not exist at character 361537niks3-go-unit-tests> 2026-10-01 10:25:14.115 UTC [42315] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1538niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260905000000_add_claims.sql (62.84ms)1539niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20251218171726_add_pins.sql (39.2ms)1540niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20251218171726_add_pins.sql (61.63ms)1541niks3-go-unit-tests> --- PASS: TestClientPushesUseOnePush (6.74s)1542niks3-go-unit-tests> === CONT  TestService_healthCheckHandler1543niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260920000000_drop_claims.sql (60.93ms)1544niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260628120000_add_object_size_and_stats.sql (37.9ms)1545niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Received push request method=POST path=/api/pushes1546niks3-go-unit-tests> --- PASS: TestClientFallsBackToClosures (6.89s)1547niks3-go-unit-tests> === CONT  TestGracefulShutdownDrainsInflight1548niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Starting HTTP server address=127.0.0.1:619641549niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Shutdown signal received, draining in-flight requests timeout=10s1550niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260923120000_add_pushes.sql (13.02ms)1551niks3-go-unit-tests> 2026/10/01 10:25:14 goose: successfully migrated database to version: 202609231200001552niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1553niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading lb2x2ab399zp2pgpyr6jig8ma3wpnnpa-pinned-file.txt (128B)1554niks3-go-unit-tests> 2026/10/01 10:25:14 OK   1_commit_pending_closure.sql (946.38µs)1555niks3-go-unit-tests> 2026/10/01 10:25:14 OK   2_object_stats_trigger.sql (252.54µs)1556niks3-go-unit-tests> 2026/10/01 10:25:14 OK   3_commit_push.sql (231.17µs)1557niks3-go-unit-tests> 2026/10/01 10:25:14 goose: up to current file version: 31558niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260628120000_add_object_size_and_stats.sql (41.47ms)1559niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=lb2x2ab399zp2pgpyr6jig8ma3wpnnpa.ls error="server returned 404: 404 page not found\n"1560niks3-go-unit-tests> --- PASS: TestConcurrentCommitsSharingObjectsDoNotDeadlock (7.19s)1561niks3-go-unit-tests> === CONT  TestGCTaskStore_Fail1562niks3-go-unit-tests> --- PASS: TestGCTaskStore_Fail (0.00s)1563niks3-go-unit-tests> === CONT  TestGCTaskStore_PhaseUpdates1564niks3-go-unit-tests> --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)1565niks3-go-unit-tests> === CONT  TestGCTaskStore_CompletedAllowsNewTask1566niks3-go-unit-tests> --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)1567niks3-go-unit-tests> === CONT  TestGCTaskStore_GetReturnsLatest1568niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)1569niks3-go-unit-tests> === CONT  TestGCTaskStore_GetEmpty1570niks3-go-unit-tests> --- PASS: TestGCTaskStore_GetEmpty (0.00s)1571niks3-go-unit-tests> === CONT  TestGCTaskStore_ConflictDifferentParams1572niks3-go-unit-tests> --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)1573niks3-go-unit-tests> === CONT  TestPresignedUploadRegisteredBeforeCommit1574niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1575niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=nar/0qmhsg42pqsz7x6hh02j8gznnac3yi3qpjhrqbwvz88i194jbb5s.nar.zst error="server returned 404: 404 page not found\n"1576niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Signed narinfos id=1 count=11577niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading 1 narinfos1578niks3-go-unit-tests> --- PASS: TestGracefulShutdownDrainsInflight (0.07s)1579niks3-go-unit-tests> === CONT  TestProxyHeadersOnlyTrustedOnSocket1580niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260905000000_add_claims.sql (104.2ms)1581niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Received complete push request method=POST path=/api/pushes/1/complete1582niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=lb2x2ab399zp2pgpyr6jig8ma3wpnnpa.narinfo error="server returned 404: 404 page not found\n"1583niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260905000000_add_claims.sql (115.31ms)1584niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Upload complete. (222ms)1585niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260920000000_drop_claims.sql (62.55ms)1586niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260923120000_add_pushes.sql (32.34ms)1587niks3-go-unit-tests> 2026/10/01 10:25:14 goose: successfully migrated database to version: 202609231200001588niks3-go-unit-tests> 2026/10/01 10:25:14 OK   1_commit_pending_closure.sql (859.88µs)1589niks3-go-unit-tests> 2026/10/01 10:25:14 OK   2_object_stats_trigger.sql (265µs)1590niks3-go-unit-tests> 2026/10/01 10:25:14 OK   3_commit_push.sql (169.79µs)1591niks3-go-unit-tests> 2026/10/01 10:25:14 goose: up to current file version: 31592niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260920000000_drop_claims.sql (69.67ms)1593niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260923120000_add_pushes.sql (33.26ms)1594niks3-go-unit-tests> 2026/10/01 10:25:14 goose: successfully migrated database to version: 202609231200001595niks3-go-unit-tests> 2026/10/01 10:25:14 OK   1_commit_pending_closure.sql (1.33ms)1596niks3-go-unit-tests> 2026/10/01 10:25:14 OK   2_object_stats_trigger.sql (217.5µs)1597niks3-go-unit-tests> 2026/10/01 10:25:14 OK   3_commit_push.sql (197.83µs)1598niks3-go-unit-tests> 2026/10/01 10:25:14 goose: up to current file version: 31599niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Received push request method=POST path=/api/pushes1600niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1601niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading 7kly396rygy4hflpybk3m4gfw2845c5x-unpinned-file.txt (128B)1602niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=7kly396rygy4hflpybk3m4gfw2845c5x.ls error="server returned 404: 404 page not found\n"1603niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=nar/0gh0wdnr6gmk4r5sfzzh12isnl14rgmkrwy1ws41hpsrlndclg5h.nar.zst error="server returned 404: 404 page not found\n"1604niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign1605niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Signed narinfos id=2 count=11606niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading 1 narinfos1607niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20241026095416_initial_model.sql (307.48ms)1608niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Received complete push request method=POST path=/api/pushes/2/complete1609niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=7kly396rygy4hflpybk3m4gfw2845c5x.narinfo error="server returned 404: 404 page not found\n"1610niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Upload complete. (138ms)1611niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20251210153512_drop_unused_gin_index.sql (16.09ms)1612niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Starting cleanup of old closures method=DELETE path=/api/closures1613niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Garbage collection started1614niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Aborted multipart uploads count=0 kept=01615niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Force mode enabled - objects will be deleted immediately without grace period1616niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20251218171726_add_pins.sql (71.01ms)1617niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260628120000_add_object_size_and_stats.sql (78.12ms)1618niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=3 objects-failed-to-delete=01619niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Vacuumed table table=pending_closures1620niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260905000000_add_claims.sql (94.39ms)1621niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Vacuumed table table=pending_objects1622niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Vacuumed table table=multipart_uploads1623niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Received push request method=POST path=/api/pushes1624niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260920000000_drop_claims.sql (83.28ms)1625niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Vacuumed table table=closures1626niks3-go-unit-tests> 2026/10/01 10:25:14 OK   20260923120000_add_pushes.sql (112.39ms)1627niks3-go-unit-tests> 2026/10/01 10:25:14 goose: successfully migrated database to version: 202609231200001628niks3-go-unit-tests> 2026/10/01 10:25:14 OK   1_commit_pending_closure.sql (1.55ms)1629niks3-go-unit-tests> 2026/10/01 10:25:14 OK   2_object_stats_trigger.sql (340.29µs)1630niks3-go-unit-tests> 2026/10/01 10:25:14 OK   3_commit_push.sql (269.75µs)1631niks3-go-unit-tests> 2026/10/01 10:25:14 goose: up to current file version: 31632niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading 2 paths to 127.0.0.1 (0 already cached)1633niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading 45yrnsxhvixsnvxs6mk33inmjxxxbgpl-top (256B)1634niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Uploading 290ilgv60lwv89qj4kq47jcvi806ag66-shared-dep (136B)1635niks3-go-unit-tests> 2026/10/01 10:25:14 INFO Vacuumed table table=objects1636niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=290ilgv60lwv89qj4kq47jcvi806ag66.ls error="server returned 404: 404 page not found\n"1637niks3-go-unit-tests> 2026/10/01 10:25:14 WARN Failed to register uploaded object key=45yrnsxhvixsnvxs6mk33inmjxxxbgpl.ls error="server returned 404: 404 page not found\n"1638niks3-go-unit-tests> 2026/10/01 10:25:15 WARN Failed to register uploaded object key=nar/1dg4ri7553s4lj6dc6bq8j98jpk4yiwcf664h1dcmiras6qpnj2i.nar.zst error="server returned 404: 404 page not found\n"1639niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1640niks3-go-unit-tests> 2026/10/01 10:25:15 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"1641niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Signed narinfos id=1 count=21642niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Uploading 2 narinfos1643niks3-go-unit-tests> 2026/10/01 10:25:15 WARN Failed to register uploaded object key=45yrnsxhvixsnvxs6mk33inmjxxxbgpl.narinfo error="server returned 404: 404 page not found\n"1644niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Received complete push request method=POST path=/api/pushes/1/complete1645niks3-go-unit-tests> 2026-10-01 10:25:15.049 UTC [42347] ERROR:  relation "goose_db_version" does not exist at character 361646niks3-go-unit-tests> 2026-10-01 10:25:15.049 UTC [42347] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1647niks3-go-unit-tests> 2026/10/01 10:25:15 WARN Failed to register uploaded object key=290ilgv60lwv89qj4kq47jcvi806ag66.narinfo error="server returned 404: 404 page not found\n"1648niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Received push request method=POST path=/api/pushes1649niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Upload complete. (368ms)1650niks3-go-unit-tests> === NAME  TestClientSharedPathCommittedMidPush1651niks3-go-unit-tests>     client_integration_test.go:680: Retrieved narinfo from S3:1652niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-41517-960550241/TestClientSharedPathCommittedMidPush258960230/001/store/290ilgv60lwv89qj4kq47jcvi806ag66-shared-dep1653niks3-go-unit-tests>         URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1654niks3-go-unit-tests>         Compression: zstd1655niks3-go-unit-tests>         NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821656niks3-go-unit-tests>         NarSize: 1361657niks3-go-unit-tests>         References: 1658niks3-go-unit-tests>         CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1659niks3-go-unit-tests>     client_integration_test.go:680: Retrieved narinfo from S3:1660niks3-go-unit-tests>         StorePath: /nix/var/nix/builds/nix-41517-960550241/TestClientSharedPathCommittedMidPush258960230/001/store/45yrnsxhvixsnvxs6mk33inmjxxxbgpl-top1661niks3-go-unit-tests>         URL: nar/1dg4ri7553s4lj6dc6bq8j98jpk4yiwcf664h1dcmiras6qpnj2i.nar.zst1662niks3-go-unit-tests>         Compression: zstd1663niks3-go-unit-tests>         NarHash: sha256:1dg4ri7553s4lj6dc6bq8j98jpk4yiwcf664h1dcmiras6qpnj2i1664niks3-go-unit-tests>         NarSize: 2561665niks3-go-unit-tests>         References: /nix/var/nix/builds/nix-41517-960550241/TestClientSharedPathCommittedMidPush258960230/001/store/290ilgv60lwv89qj4kq47jcvi806ag66-shared-dep1666niks3-go-unit-tests>         CA: text:sha256:19ll25vmjg5gc77g9fqc77i8p29npq4yqhb45dlc3ihi5lfhgkix1667niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Received complete push request method=POST path=/api/pushes/1/complete1668niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Received push request method=POST path=/api/pushes1669niks3-go-unit-tests> --- PASS: TestClientSharedPathCommittedMidPush (6.53s)1670niks3-go-unit-tests> === CONT  TestReadRedirectNar1671niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Aborted multipart uploads count=0 kept=01672niks3-go-unit-tests> 2026/10/01 10:25:15 WARN Force mode enabled - objects will be deleted immediately without grace period1673niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=2 objects-deleted-after-grace-period=2 objects-failed-to-delete=01674niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Vacuumed table table=pending_closures1675niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Vacuumed table table=pending_objects1676niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Vacuumed table table=multipart_uploads1677niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Vacuumed table table=closures1678niks3-go-unit-tests> === NAME  TestClientWithDependencies1679niks3-go-unit-tests>     client_integration_test.go:613: Built derivation: /nix/var/nix/builds/nix-41517-960550241/TestClientWithDependencies1066639235/001/store/2blizwb9sy6d27a66kg5d826wxc9j4mc-test-script1680niks3-go-unit-tests> 2026/10/01 10:25:15 OK   20241026095416_initial_model.sql (362.31ms)1681niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Received uploads request method=POST path=/api/pending_closures1682niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Vacuumed table table=objects1683niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Received complete push request method=POST path=/api/pushes/2/complete1684niks3-go-unit-tests> 2026/10/01 10:25:15 OK   20251210153512_drop_unused_gin_index.sql (9.83ms)1685niks3-go-unit-tests> --- PASS: TestPush_SkippedKeySurvivesGCBeforeCommit (5.64s)1686niks3-go-unit-tests> === CONT  TestReadProxyDisabled1687niks3-go-unit-tests> 2026/10/01 10:25:15 OK   20251218171726_add_pins.sql (50.59ms)1688niks3-go-unit-tests> === NAME  TestClientWithDependencies1689niks3-go-unit-tests>     client_integration_test.go:615: Found 1 dependencies (including self)1690niks3-go-unit-tests> 2026/10/01 10:25:15 OK   20260628120000_add_object_size_and_stats.sql (47.39ms)1691niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Received push request method=POST path=/api/pushes1692niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1693niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Uploading 2blizwb9sy6d27a66kg5d826wxc9j4mc-test-script (136B)1694niks3-go-unit-tests> 2026/10/01 10:25:15 OK   20260905000000_add_claims.sql (92.47ms)1695niks3-go-unit-tests> 2026/10/01 10:25:15 WARN Failed to register uploaded object key=2blizwb9sy6d27a66kg5d826wxc9j4mc.ls error="server returned 404: 404 page not found\n"1696niks3-go-unit-tests> 2026/10/01 10:25:15 WARN Failed to register uploaded object key=nar/1s7yghixzpinyqm7g59kyn3sav81pmnsdi5v63m6x4r2vacy907j.nar.zst error="server returned 404: 404 page not found\n"1697niks3-go-unit-tests> 2026/10/01 10:25:15 WARN Failed to register uploaded object key=log/n9z69nxd2g7lj6yvagjklxs4jb0caadl-test-script.drv error="server returned 404: 404 page not found\n"1698niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1699niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Signed narinfos id=1 count=11700niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Uploading 1 narinfos1701niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Received complete push request method=POST path=/api/pushes/1/complete1702niks3-go-unit-tests> 2026/10/01 10:25:15 WARN Failed to register uploaded object key=2blizwb9sy6d27a66kg5d826wxc9j4mc.narinfo error="server returned 404: 404 page not found\n"1703niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Upload complete. (171ms)1704niks3-go-unit-tests>     client_integration_test.go:617: Skipping nix copy test - isolated store (/nix/var/nix/builds/nix-41517-960550241/TestClientWithDependencies1066639235/001/store) requires matching store prefix1705niks3-go-unit-tests> 2026/10/01 10:25:15 OK   20260920000000_drop_claims.sql (70.61ms)1706niks3-go-unit-tests> 2026/10/01 10:25:15 OK   20260923120000_add_pushes.sql (18.57ms)1707niks3-go-unit-tests> 2026/10/01 10:25:15 goose: successfully migrated database to version: 202609231200001708niks3-go-unit-tests> 2026/10/01 10:25:15 OK   1_commit_pending_closure.sql (1.35ms)1709niks3-go-unit-tests> 2026/10/01 10:25:15 OK   2_object_stats_trigger.sql (298.17µs)1710niks3-go-unit-tests> 2026/10/01 10:25:15 OK   3_commit_push.sql (231.04µs)1711niks3-go-unit-tests> 2026/10/01 10:25:15 goose: up to current file version: 31712niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1713niks3-go-unit-tests> 2026/10/01 10:25:15 WARN CompleteMultipartUpload errored but object exists; treating as success error="The specified key does not exist." object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=NGQ5MGM3NzItZmU4NC00NjdiLWI1NjgtZTY0ZTg0MTg2ZTA5LjY4ZWQyMzMyLWQ5MzUtNDk4Ni1hYzc1LWUyOTNkZDdiNmQ5ZXgxNzkwODUwMzE1NTUzNTI1MDAw1714niks3-go-unit-tests> 2026/10/01 10:25:15 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=NGQ5MGM3NzItZmU4NC00NjdiLWI1NjgtZTY0ZTg0MTg2ZTA5LjY4ZWQyMzMyLWQ5MzUtNDk4Ni1hYzc1LWUyOTNkZDdiNmQ5ZXgxNzkwODUwMzE1NTUzNTI1MDAw parts=11715niks3-go-unit-tests> --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (5.78s)1716niks3-go-unit-tests> === CONT  TestReadProxyRootRedirectsToIndexHTML1717niks3-go-unit-tests> --- PASS: TestService_Rustfstest (5.00s)1718niks3-go-unit-tests> === CONT  TestReadProxyConditionalGet1719niks3-go-unit-tests> --- PASS: TestClientWithDependencies (6.20s)1720niks3-go-unit-tests> === CONT  TestReadProxyOutlastsServerWriteTimeout1721niks3-go-unit-tests> 2026/10/01 10:25:16 WARN readiness check failed error="closed pool"1722niks3-go-unit-tests> --- PASS: TestService_readinessHandler (4.49s)1723niks3-go-unit-tests> === CONT  TestReadProxyHead1724niks3-go-unit-tests> 2026-10-01 10:25:16.270 UTC [42379] ERROR:  relation "goose_db_version" does not exist at character 361725niks3-go-unit-tests> 2026-10-01 10:25:16.270 UTC [42379] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1726niks3-go-unit-tests> 2026-10-01 10:25:16.274 UTC [42380] ERROR:  relation "goose_db_version" does not exist at character 361727niks3-go-unit-tests> 2026-10-01 10:25:16.274 UTC [42380] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1728niks3-go-unit-tests> 2026-10-01 10:25:16.288 UTC [42381] ERROR:  relation "goose_db_version" does not exist at character 361729niks3-go-unit-tests> 2026-10-01 10:25:16.288 UTC [42381] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1730niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20241026095416_initial_model.sql (12.39ms)1731niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20251210153512_drop_unused_gin_index.sql (538.96µs)1732niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20251218171726_add_pins.sql (3.73ms)1733niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20241026095416_initial_model.sql (30.47ms)1734niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20251210153512_drop_unused_gin_index.sql (855.67µs)1735niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20260628120000_add_object_size_and_stats.sql (25.24ms)1736niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20251218171726_add_pins.sql (10.69ms)1737niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20260905000000_add_claims.sql (28ms)1738niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20260628120000_add_object_size_and_stats.sql (28.32ms)1739niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20260920000000_drop_claims.sql (14.43ms)1740niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20260905000000_add_claims.sql (20.83ms)1741niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20260923120000_add_pushes.sql (7.41ms)1742niks3-go-unit-tests> 2026/10/01 10:25:16 goose: successfully migrated database to version: 202609231200001743niks3-go-unit-tests> 2026/10/01 10:25:16 OK   1_commit_pending_closure.sql (1.39ms)1744niks3-go-unit-tests> 2026/10/01 10:25:16 OK   2_object_stats_trigger.sql (252.42µs)1745niks3-go-unit-tests> 2026/10/01 10:25:16 OK   3_commit_push.sql (206.21µs)1746niks3-go-unit-tests> 2026/10/01 10:25:16 goose: up to current file version: 31747niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20241026095416_initial_model.sql (82.99ms)1748niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20251210153512_drop_unused_gin_index.sql (8.25ms)1749niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20260920000000_drop_claims.sql (21.23ms)1750niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20260923120000_add_pushes.sql (6.15ms)1751niks3-go-unit-tests> 2026/10/01 10:25:16 goose: successfully migrated database to version: 202609231200001752niks3-go-unit-tests> 2026/10/01 10:25:16 OK   1_commit_pending_closure.sql (1.15ms)1753niks3-go-unit-tests> 2026/10/01 10:25:16 OK   2_object_stats_trigger.sql (245.5µs)1754niks3-go-unit-tests> 2026/10/01 10:25:16 OK   3_commit_push.sql (232.17µs)1755niks3-go-unit-tests> 2026/10/01 10:25:16 goose: up to current file version: 31756niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20251218171726_add_pins.sql (17.5ms)1757niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20260628120000_add_object_size_and_stats.sql (24.52ms)1758niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20260905000000_add_claims.sql (38.06ms)1759niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20260920000000_drop_claims.sql (13.02ms)1760niks3-go-unit-tests> 2026/10/01 10:25:16 OK   20260923120000_add_pushes.sql (5.63ms)1761niks3-go-unit-tests> 2026/10/01 10:25:16 goose: successfully migrated database to version: 202609231200001762niks3-go-unit-tests> 2026/10/01 10:25:16 OK   1_commit_pending_closure.sql (1.64ms)1763niks3-go-unit-tests> 2026/10/01 10:25:16 OK   2_object_stats_trigger.sql (323.5µs)1764niks3-go-unit-tests> 2026/10/01 10:25:16 OK   3_commit_push.sql (278.46µs)1765niks3-go-unit-tests> 2026/10/01 10:25:16 goose: up to current file version: 31766niks3-go-unit-tests> --- PASS: TestService_healthCheckHandler (2.37s)1767niks3-go-unit-tests> === CONT  TestReadProxyInvalidPath1768niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=3 objects_failed=01769niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Received create pin request method=POST path=/api/pins/myapp1770niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Created/updated pin name=myapp store_path=/nix/var/nix/builds/nix-41517-960550241/TestPinProtectsFromGC3268778793/001/store/lb2x2ab399zp2pgpyr6jig8ma3wpnnpa-pinned-file.txt narinfo_key=lb2x2ab399zp2pgpyr6jig8ma3wpnnpa.narinfo1771niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Starting cleanup of old closures method=DELETE path=/api/closures1772niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Garbage collection started1773niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Aborted multipart uploads count=0 kept=01774niks3-go-unit-tests> 2026/10/01 10:25:16 WARN Force mode enabled - objects will be deleted immediately without grace period1775niks3-go-unit-tests> 2026/10/01 10:25:16 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=01776niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Vacuumed table table=pending_closures1777niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Vacuumed table table=pending_objects1778niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Vacuumed table table=multipart_uploads1779niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Vacuumed table table=closures1780niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Vacuumed table table=objects1781niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Received uploads request method=POST path=/api/pending_closures1782niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Registered completed upload object_key=nar/jjjjjjjjjjjjjjjjjjjjjjjjjjjjjj0100000000000000000000.nar.zst1783niks3-go-unit-tests> 2026/10/01 10:25:16 INFO Received uploads request method=POST path=/api/pending_closures1784niks3-go-unit-tests> --- PASS: TestPresignedUploadRegisteredBeforeCommit (2.62s)1785niks3-go-unit-tests> === CONT  TestReadProxy4041786niks3-go-unit-tests> 2026/10/01 10:25:17 INFO Starting HTTP server address=/nix/var/nix/builds/nix-41517-960550241/TestProxyHeadersOnlyTrustedOnSocket86086153/001/proxy.sock1787niks3-go-unit-tests> 2026/10/01 10:25:17 INFO Starting HTTP server address=127.0.0.1:620401788niks3-go-unit-tests> 2026/10/01 10:25:17 WARN mTLS auth: subject not in bound subjects subject="CN=someone"1789niks3-go-unit-tests> 2026/10/01 10:25:17 INFO Shutdown signal received, draining in-flight requests timeout=10s1790niks3-go-unit-tests> --- PASS: TestProxyHeadersOnlyTrustedOnSocket (2.93s)1791niks3-go-unit-tests> === CONT  TestReadProxyNarStreaming1792niks3-go-unit-tests> 2026-10-01 10:25:17.484 UTC [42388] ERROR:  relation "goose_db_version" does not exist at character 361793niks3-go-unit-tests> 2026-10-01 10:25:17.484 UTC [42388] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1794niks3-go-unit-tests> 2026-10-01 10:25:17.600 UTC [42389] ERROR:  relation "goose_db_version" does not exist at character 361795niks3-go-unit-tests> 2026-10-01 10:25:17.600 UTC [42389] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1796niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20241026095416_initial_model.sql (147.95ms)1797niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20251210153512_drop_unused_gin_index.sql (11.96ms)1798niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20251218171726_add_pins.sql (17.27ms)1799niks3-go-unit-tests> 2026-10-01 10:25:17.740 UTC [42390] ERROR:  relation "goose_db_version" does not exist at character 361800niks3-go-unit-tests> 2026-10-01 10:25:17.740 UTC [42390] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1801niks3-go-unit-tests> 2026-10-01 10:25:17.746 UTC [42391] ERROR:  relation "goose_db_version" does not exist at character 361802niks3-go-unit-tests> 2026-10-01 10:25:17.746 UTC [42391] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1803niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20260628120000_add_object_size_and_stats.sql (41.9ms)1804niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20241026095416_initial_model.sql (160.69ms)1805niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20251210153512_drop_unused_gin_index.sql (13.96ms)1806niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20260905000000_add_claims.sql (76.99ms)1807niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20251218171726_add_pins.sql (31.22ms)1808niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20260920000000_drop_claims.sql (32.15ms)1809niks3-go-unit-tests> 2026-10-01 10:25:17.893 UTC [42392] ERROR:  relation "goose_db_version" does not exist at character 361810niks3-go-unit-tests> 2026-10-01 10:25:17.893 UTC [42392] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1811niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20260628120000_add_object_size_and_stats.sql (20.33ms)1812niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20260923120000_add_pushes.sql (6.3ms)1813niks3-go-unit-tests> 2026/10/01 10:25:17 goose: successfully migrated database to version: 202609231200001814niks3-go-unit-tests> 2026/10/01 10:25:17 OK   1_commit_pending_closure.sql (5.48ms)1815niks3-go-unit-tests> 2026/10/01 10:25:17 OK   2_object_stats_trigger.sql (1.4ms)1816niks3-go-unit-tests> 2026/10/01 10:25:17 OK   3_commit_push.sql (870.17µs)1817niks3-go-unit-tests> 2026/10/01 10:25:17 goose: up to current file version: 31818niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20260905000000_add_claims.sql (11.43ms)1819niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20260920000000_drop_claims.sql (46.49ms)1820niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20241026095416_initial_model.sql (132.57ms)1821niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20241026095416_initial_model.sql (142.85ms)1822niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20260923120000_add_pushes.sql (21.39ms)1823niks3-go-unit-tests> 2026/10/01 10:25:17 goose: successfully migrated database to version: 202609231200001824niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20251210153512_drop_unused_gin_index.sql (16.15ms)1825niks3-go-unit-tests> 2026/10/01 10:25:17 OK   1_commit_pending_closure.sql (4.06ms)1826niks3-go-unit-tests> 2026/10/01 10:25:17 OK   2_object_stats_trigger.sql (860.29µs)1827niks3-go-unit-tests> 2026/10/01 10:25:17 OK   3_commit_push.sql (578.79µs)1828niks3-go-unit-tests> 2026/10/01 10:25:17 goose: up to current file version: 31829niks3-go-unit-tests> 2026/10/01 10:25:17 OK   20251210153512_drop_unused_gin_index.sql (11.73ms)1830niks3-go-unit-tests> 2026-10-01 10:25:17.993 UTC [42393] ERROR:  relation "goose_db_version" does not exist at character 361831niks3-go-unit-tests> 2026-10-01 10:25:17.993 UTC [42393] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1832niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20251218171726_add_pins.sql (28.43ms)1833niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20251218171726_add_pins.sql (39.34ms)1834niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260628120000_add_object_size_and_stats.sql (37.11ms)1835niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260628120000_add_object_size_and_stats.sql (44.99ms)1836niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260905000000_add_claims.sql (89.69ms)1837niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260920000000_drop_claims.sql (52.18ms)1838niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260905000000_add_claims.sql (159.5ms)1839niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260923120000_add_pushes.sql (45.36ms)1840niks3-go-unit-tests> 2026/10/01 10:25:18 goose: successfully migrated database to version: 202609231200001841niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20241026095416_initial_model.sql (290.54ms)1842niks3-go-unit-tests> 2026/10/01 10:25:18 OK   1_commit_pending_closure.sql (5.5ms)1843niks3-go-unit-tests> 2026/10/01 10:25:18 OK   2_object_stats_trigger.sql (3.96ms)1844niks3-go-unit-tests> 2026/10/01 10:25:18 OK   3_commit_push.sql (1.49ms)1845niks3-go-unit-tests> 2026/10/01 10:25:18 goose: up to current file version: 31846niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20251210153512_drop_unused_gin_index.sql (10.88ms)1847niks3-go-unit-tests> --- PASS: TestReadRedirectNar (2.98s)1848niks3-go-unit-tests> === CONT  TestReadProxyNarinfoAlreadyDecompressed1849niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20251218171726_add_pins.sql (29.77ms)1850niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20241026095416_initial_model.sql (214.27ms)1851niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260920000000_drop_claims.sql (50.34ms)1852niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20251210153512_drop_unused_gin_index.sql (20.21ms)1853niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260923120000_add_pushes.sql (28.49ms)1854niks3-go-unit-tests> 2026/10/01 10:25:18 goose: successfully migrated database to version: 202609231200001855niks3-go-unit-tests> 2026/10/01 10:25:18 OK   1_commit_pending_closure.sql (2.26ms)1856niks3-go-unit-tests> 2026/10/01 10:25:18 OK   2_object_stats_trigger.sql (497.92µs)1857niks3-go-unit-tests> 2026/10/01 10:25:18 OK   3_commit_push.sql (398.25µs)1858niks3-go-unit-tests> 2026/10/01 10:25:18 goose: up to current file version: 31859niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260628120000_add_object_size_and_stats.sql (51.07ms)1860niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20251218171726_add_pins.sql (34.65ms)1861niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260628120000_add_object_size_and_stats.sql (50.96ms)1862niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260905000000_add_claims.sql (97.62ms)1863niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260920000000_drop_claims.sql (61.32ms)1864niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260905000000_add_claims.sql (104.07ms)1865niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260923120000_add_pushes.sql (20.67ms)1866niks3-go-unit-tests> 2026/10/01 10:25:18 goose: successfully migrated database to version: 202609231200001867niks3-go-unit-tests> 2026/10/01 10:25:18 OK   1_commit_pending_closure.sql (4.67ms)1868niks3-go-unit-tests> 2026/10/01 10:25:18 OK   2_object_stats_trigger.sql (1.71ms)1869niks3-go-unit-tests> 2026/10/01 10:25:18 OK   3_commit_push.sql (743.04µs)1870niks3-go-unit-tests> 2026/10/01 10:25:18 goose: up to current file version: 31871niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260920000000_drop_claims.sql (55.35ms)1872niks3-go-unit-tests> 2026/10/01 10:25:18 OK   20260923120000_add_pushes.sql (22.56ms)1873niks3-go-unit-tests> 2026/10/01 10:25:18 goose: successfully migrated database to version: 202609231200001874niks3-go-unit-tests> 2026/10/01 10:25:18 OK   1_commit_pending_closure.sql (5.06ms)1875niks3-go-unit-tests> 2026/10/01 10:25:18 OK   2_object_stats_trigger.sql (1.22ms)1876niks3-go-unit-tests> 2026/10/01 10:25:18 OK   3_commit_push.sql (829.63µs)1877niks3-go-unit-tests> 2026/10/01 10:25:18 goose: up to current file version: 31878niks3-go-unit-tests> === NAME  TestPinProtectsFromGC1879niks3-go-unit-tests>     client_integration_test.go:840: Pin successfully protected closure from garbage collection1880niks3-go-unit-tests> --- PASS: TestReadProxyDisabled (3.06s)1881niks3-go-unit-tests> === CONT  TestReadProxyNarinfo1882niks3-go-unit-tests> --- PASS: TestPinProtectsFromGC (10.78s)1883niks3-go-unit-tests> === CONT  TestIsValidCachePath1884niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo1885niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo1886niks3-go-unit-tests> === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars1887niks3-go-unit-tests> === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars1888niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_zst1889niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_zst1890niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_xz1891niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_xz1892niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_bz21893niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_bz21894niks3-go-unit-tests> === RUN   TestIsValidCachePath/nar_uncompressed1895niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nar_uncompressed1896niks3-go-unit-tests> === RUN   TestIsValidCachePath/ls1897niks3-go-unit-tests> === PAUSE TestIsValidCachePath/ls1898niks3-go-unit-tests> === RUN   TestIsValidCachePath/log1899niks3-go-unit-tests> === PAUSE TestIsValidCachePath/log1900niks3-go-unit-tests> === RUN   TestIsValidCachePath/realisation1901niks3-go-unit-tests> === PAUSE TestIsValidCachePath/realisation1902niks3-go-unit-tests> === RUN   TestIsValidCachePath/nix-cache-info1903niks3-go-unit-tests> === PAUSE TestIsValidCachePath/nix-cache-info1904niks3-go-unit-tests> === RUN   TestIsValidCachePath/index.html1905niks3-go-unit-tests> === PAUSE TestIsValidCachePath/index.html1906niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_parent1907niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_parent1908niks3-go-unit-tests> === RUN   TestIsValidCachePath/traversal_in_middle1909niks3-go-unit-tests> === PAUSE TestIsValidCachePath/traversal_in_middle1910niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_e1911niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_e1912niks3-go-unit-tests> === RUN   TestIsValidCachePath/invalid_char_u1913niks3-go-unit-tests> === PAUSE TestIsValidCachePath/invalid_char_u1914niks3-go-unit-tests> === RUN   TestIsValidCachePath/random_path1915niks3-go-unit-tests> === PAUSE TestIsValidCachePath/random_path1916niks3-go-unit-tests> === RUN   TestIsValidCachePath/empty1917niks3-go-unit-tests> === PAUSE TestIsValidCachePath/empty1918niks3-go-unit-tests> === RUN   TestIsValidCachePath/leading_slash1919niks3-go-unit-tests> === PAUSE TestIsValidCachePath/leading_slash1920niks3-go-unit-tests> === RUN   TestIsValidCachePath/wrong_extension1921niks3-go-unit-tests> === PAUSE TestIsValidCachePath/wrong_extension1922niks3-go-unit-tests> === RUN   TestIsValidCachePath/short_hash1923niks3-go-unit-tests> === PAUSE TestIsValidCachePath/short_hash1924niks3-go-unit-tests> === CONT  TestGetExistingObjectsLocksRows