niks3-go-unit-tests
checks.aarch64-darwin.go-unit-tests
· build #377
· raw
1Running client tests...2=== RUN TestDoServerRequestAttachesToken3=== PAUSE TestDoServerRequestAttachesToken4=== RUN TestRegisterUploadedObjectReusesConnections5=== PAUSE TestRegisterUploadedObjectReusesConnections6=== RUN TestRunGarbageCollection_FinishedOnAnotherReplica7=== PAUSE TestRunGarbageCollection_FinishedOnAnotherReplica8=== RUN TestRunGarbageCollection_NotFoundAfterLocalRun9=== PAUSE TestRunGarbageCollection_NotFoundAfterLocalRun10=== RUN TestCaseHackSuffix11=== PAUSE TestCaseHackSuffix12=== RUN TestFilterOversizedClosures13=== PAUSE TestFilterOversizedClosures14=== RUN TestUploadMultipart_PartsInParallel15=== PAUSE TestUploadMultipart_PartsInParallel16=== RUN TestPartSizeForNAR17=== PAUSE TestPartSizeForNAR18=== RUN TestUploadMultipart_SupersededByPeer19=== PAUSE TestUploadMultipart_SupersededByPeer20=== RUN TestDumpPathCaseHackMatchesNix21--- PASS: TestDumpPathCaseHackMatchesNix (0.07s)22=== RUN TestDumpPathCaseHackCollision23--- PASS: TestDumpPathCaseHackCollision (0.00s)24=== RUN TestDumpPathMatchesNix25=== PAUSE TestDumpPathMatchesNix26=== RUN TestDumpPathSingleFile27=== PAUSE TestDumpPathSingleFile28=== RUN TestDumpPathWriterError29=== PAUSE TestDumpPathWriterError30=== RUN TestEncodeNixBase3231=== PAUSE TestEncodeNixBase3232=== RUN TestEncodeNixBase32WithRealHash33=== PAUSE TestEncodeNixBase32WithRealHash34=== RUN TestConvertHashToNix3235=== PAUSE TestConvertHashToNix3236=== RUN TestGetStorePathHash37=== PAUSE TestGetStorePathHash38=== RUN TestPathInfoHashCompatibility39=== PAUSE TestPathInfoHashCompatibility40=== RUN TestParsePathInfoJSON41=== PAUSE TestParsePathInfoJSON42=== RUN TestParsePathInfoJSONMultiplePaths43=== PAUSE TestParsePathInfoJSONMultiplePaths44=== RUN TestPathInfoCACompatibility45=== PAUSE TestPathInfoCACompatibility46=== RUN TestRateLimiterFeedback47=== PAUSE TestRateLimiterFeedback48=== RUN TestRateLimiterFeedback_400DoesNotCountAsSuccess49=== PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess50=== RUN TestResolveStorePath51=== PAUSE TestResolveStorePath52=== RUN TestDoWithRetry_BodyReplayedViaGetBody53=== PAUSE TestDoWithRetry_BodyReplayedViaGetBody54=== RUN TestShellSplit55=== PAUSE TestShellSplit56=== RUN TestShellSplitErrors57=== PAUSE TestShellSplitErrors58=== RUN TestStreamPushReportsEveryPath59=== PAUSE TestStreamPushReportsEveryPath60=== RUN TestStreamPushBatchesUnderLoad61=== PAUSE TestStreamPushBatchesUnderLoad62=== RUN TestStreamPushIsolatesFailures63=== PAUSE TestStreamPushIsolatesFailures64=== RUN TestStreamPushGivesUpOnDeadServer65=== PAUSE TestStreamPushGivesUpOnDeadServer66=== RUN TestStreamPushRequestLine67=== PAUSE TestStreamPushRequestLine68=== RUN TestStreamPushReportsSignatures69=== PAUSE TestStreamPushReportsSignatures70=== RUN TestClientSignaturesByStorePath71=== PAUSE TestClientSignaturesByStorePath72=== RUN TestSetClientTLS73=== PAUSE TestSetClientTLS74=== RUN TestSetClientTLSDoesNotMutateDefaultTransport75=== PAUSE TestSetClientTLSDoesNotMutateDefaultTransport76=== RUN TestSetClientTLSErrors77=== PAUSE TestSetClientTLSErrors78=== RUN TestStaticToken79=== PAUSE TestStaticToken80=== RUN TestFileTokenReadsAndCaches81=== PAUSE TestFileTokenReadsAndCaches82=== RUN TestFileTokenMissing83=== PAUSE TestFileTokenMissing84=== RUN TestFileTokenEmpty85=== PAUSE TestFileTokenEmpty86=== RUN TestScriptTokenNoExpiryRerunsEveryCall87=== PAUSE TestScriptTokenNoExpiryRerunsEveryCall88=== RUN TestScriptTokenCachesUntilRefresh89=== PAUSE TestScriptTokenCachesUntilRefresh90=== RUN TestScriptTokenEmptyToken91=== PAUSE TestScriptTokenEmptyToken92=== RUN TestScriptTokenBadJSON93=== PAUSE TestScriptTokenBadJSON94=== RUN TestScriptTokenScriptFails95=== PAUSE TestScriptTokenScriptFails96=== RUN TestScriptTokenEmptyCommand97=== PAUSE TestScriptTokenEmptyCommand98=== CONT TestDoServerRequestAttachesToken99=== CONT TestDoWithRetry_BodyReplayedViaGetBody100=== CONT TestSetClientTLSErrors101=== CONT TestResolveStorePath102=== CONT TestEncodeNixBase32103=== RUN TestEncodeNixBase32/test_string_hash104=== PAUSE TestEncodeNixBase32/test_string_hash105=== RUN TestEncodeNixBase32/empty_input106=== PAUSE TestEncodeNixBase32/empty_input107=== CONT TestPathInfoHashCompatibility108=== RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)109=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)110=== RUN TestPathInfoHashCompatibility/old_string_format_with_colon111=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon112=== RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI113=== PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI114=== RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512115=== PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512116=== CONT TestGetStorePathHash117=== RUN TestGetStorePathHash/valid_store_path118=== PAUSE TestGetStorePathHash/valid_store_path119=== RUN TestGetStorePathHash/basename_without_hyphen_should_error120=== PAUSE TestGetStorePathHash/basename_without_hyphen_should_error121=== RUN TestGetStorePathHash/hash_with_invalid_characters_should_error122=== PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error123=== RUN TestGetStorePathHash/hash_with_wrong_length_should_error124=== PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error125=== CONT TestConvertHashToNix32126=== RUN TestConvertHashToNix32/SRI_format_to_Nix32127=== PAUSE TestConvertHashToNix32/SRI_format_to_Nix32128=== RUN TestConvertHashToNix32/already_Nix32_format129=== PAUSE TestConvertHashToNix32/already_Nix32_format130=== RUN TestConvertHashToNix32/invalid_format131=== PAUSE TestConvertHashToNix32/invalid_format132=== CONT TestEncodeNixBase32WithRealHash133--- PASS: TestEncodeNixBase32WithRealHash (0.00s)134=== CONT TestScriptTokenCachesUntilRefresh135=== CONT TestRateLimiterFeedback136=== RUN TestRateLimiterFeedback/429_enables_limiter137=== PAUSE TestRateLimiterFeedback/429_enables_limiter138=== RUN TestRateLimiterFeedback/503_enables_limiter139=== PAUSE TestRateLimiterFeedback/503_enables_limiter140=== RUN TestRateLimiterFeedback/200_does_not_enable_limiter141=== PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter142=== RUN TestRateLimiterFeedback/400_does_not_enable_limiter143=== PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter144=== CONT TestPathInfoCACompatibility145=== CONT TestScriptTokenEmptyCommand146--- PASS: TestScriptTokenEmptyCommand (0.00s)147=== CONT TestScriptTokenScriptFails148=== RUN TestPathInfoCACompatibility/null_ca_field149=== CONT TestParsePathInfoJSON150=== PAUSE TestPathInfoCACompatibility/null_ca_field151=== CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess152=== CONT TestParsePathInfoJSONMultiplePaths153=== RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths154=== PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths155=== RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths156=== PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths157=== RUN TestParsePathInfoJSON/Nix_format158=== RUN TestPathInfoCACompatibility/old_string_format_-_text159=== PAUSE TestPathInfoCACompatibility/old_string_format_-_text160=== RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive161=== PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive162=== RUN TestPathInfoCACompatibility/new_structured_format_-_text163=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_text164=== RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method165=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method166=== CONT TestScriptTokenEmptyToken1672026/10/06 11:18:21 WARN Rate limiter enabled after throttle name=server-test rate=5168=== CONT TestScriptTokenBadJSON169=== PAUSE TestParsePathInfoJSON/Nix_format170=== RUN TestParsePathInfoJSON/Lix_format171=== PAUSE TestParsePathInfoJSON/Lix_format172=== RUN TestParsePathInfoJSON/empty_input173=== PAUSE TestParsePathInfoJSON/empty_input174=== RUN TestParsePathInfoJSON/whitespace_only175=== PAUSE TestParsePathInfoJSON/whitespace_only176=== RUN TestParsePathInfoJSON/invalid_JSON177=== PAUSE TestParsePathInfoJSON/invalid_JSON178=== CONT TestFileTokenMissing179--- PASS: TestResolveStorePath (0.01s)180=== CONT TestScriptTokenNoExpiryRerunsEveryCall1812026/10/06 11:18:21 WARN Rate limiter enabled after throttle name=server-test rate=5182--- PASS: TestFileTokenMissing (0.00s)183=== CONT TestFileTokenEmpty1842026/10/06 11:18:21 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:545841852026/10/06 11:18:21 WARN Rate limiter backed off name=server-test rate=51862026/10/06 11:18:21 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:54584187--- PASS: TestDoServerRequestAttachesToken (0.01s)188=== CONT TestUploadMultipart_PartsInParallel189--- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.01s)190=== CONT TestDumpPathWriterError191--- PASS: TestScriptTokenScriptFails (0.01s)192=== CONT TestDumpPathSingleFile193=== RUN TestSetClientTLSErrors/missing_cert_file194=== PAUSE TestSetClientTLSErrors/missing_cert_file195=== RUN TestSetClientTLSErrors/missing_key_file196=== PAUSE TestSetClientTLSErrors/missing_key_file197=== RUN TestSetClientTLSErrors/missing_ca_file198=== PAUSE TestSetClientTLSErrors/missing_ca_file199=== RUN TestSetClientTLSErrors/invalid_ca_file200=== PAUSE TestSetClientTLSErrors/invalid_ca_file201=== CONT TestDumpPathMatchesNix202--- PASS: TestFileTokenEmpty (0.01s)203=== CONT TestUploadMultipart_SupersededByPeer204=== RUN TestUploadMultipart_SupersededByPeer/exists205=== PAUSE TestUploadMultipart_SupersededByPeer/exists206=== RUN TestUploadMultipart_SupersededByPeer/missing207=== PAUSE TestUploadMultipart_SupersededByPeer/missing208=== CONT TestPartSizeForNAR209=== RUN TestPartSizeForNAR/zero_stays_at_minimum210=== PAUSE TestPartSizeForNAR/zero_stays_at_minimum211=== RUN TestPartSizeForNAR/small_stays_at_minimum212=== PAUSE TestPartSizeForNAR/small_stays_at_minimum213=== RUN TestPartSizeForNAR/80_GiB_fits_at_minimum214=== PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum215=== RUN TestPartSizeForNAR/115_GiB_needs_larger_parts216=== PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts217=== RUN TestPartSizeForNAR/1_TiB218=== PAUSE TestPartSizeForNAR/1_TiB219=== RUN TestPartSizeForNAR/5_TiB_S3_max_object220=== PAUSE TestPartSizeForNAR/5_TiB_S3_max_object221=== RUN TestPartSizeForNAR/capped_at_5_GiB222=== PAUSE TestPartSizeForNAR/capped_at_5_GiB223=== CONT TestStreamPushGivesUpOnDeadServer2242026/10/06 11:18:21 ERROR Upload failed error="connection refused" count=202252026/10/06 11:18:21 ERROR Server seems unavailable, giving up on batch untried=17226--- PASS: TestStreamPushGivesUpOnDeadServer (0.00s)227=== CONT TestSetClientTLSDoesNotMutateDefaultTransport228--- PASS: TestScriptTokenBadJSON (0.01s)229=== CONT TestSetClientTLS230--- PASS: TestScriptTokenEmptyToken (0.01s)231=== CONT TestClientSignaturesByStorePath232--- PASS: TestClientSignaturesByStorePath (0.00s)233=== CONT TestStreamPushReportsSignatures2342026/10/06 11:18:21 ERROR Upload failed error=boom count=1235--- PASS: TestStreamPushReportsSignatures (0.00s)236=== CONT TestStreamPushRequestLine2372026/10/06 11:18:21 ERROR Upload failed error=boom count=1238--- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.00s)239=== CONT TestFileTokenReadsAndCaches240--- PASS: TestFileTokenReadsAndCaches (0.00s)241=== CONT TestStreamPushReportsEveryPath242--- PASS: TestStreamPushReportsEveryPath (0.00s)243=== CONT TestStreamPushIsolatesFailures2442026/10/06 11:18:21 ERROR Upload failed error="bad path" count=3245--- PASS: TestStreamPushIsolatesFailures (0.00s)246=== CONT TestStreamPushBatchesUnderLoad247=== RUN TestSetClientTLS/rejects_connection_without_client_cert248=== PAUSE TestSetClientTLS/rejects_connection_without_client_cert249=== RUN TestSetClientTLS/succeeds_with_client_cert_and_CA250=== PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA251=== RUN TestSetClientTLS/preserves_debug_logging_transport252=== PAUSE TestSetClientTLS/preserves_debug_logging_transport253=== CONT TestRunGarbageCollection_NotFoundAfterLocalRun2542026/10/06 11:18:21 INFO Garbage collection started255--- PASS: TestRunGarbageCollection_NotFoundAfterLocalRun (0.00s)256=== CONT TestFilterOversizedClosures257=== RUN TestFilterOversizedClosures/no_limit_keeps_everything258=== PAUSE TestFilterOversizedClosures/no_limit_keeps_everything259=== RUN TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped260=== PAUSE TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped261=== RUN TestFilterOversizedClosures/all_closures_skipped262=== PAUSE TestFilterOversizedClosures/all_closures_skipped263=== CONT TestCaseHackSuffix264--- PASS: TestStreamPushRequestLine (0.01s)265=== CONT TestStaticToken266--- PASS: TestStaticToken (0.00s)267=== CONT TestShellSplitErrors268--- PASS: TestShellSplitErrors (0.00s)269=== CONT TestShellSplit270--- PASS: TestShellSplit (0.00s)271=== CONT TestRunGarbageCollection_FinishedOnAnotherReplica2722026/10/06 11:18:21 INFO Garbage collection started2732026/10/06 11:18:21 INFO Garbage collection progress phase=running_on_another_replica failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=0 objects_deleted=0 objects_failed=02742026/10/06 11:18:21 WARN Garbage collection finished on another replica. Its statistics are not available here275--- PASS: TestRunGarbageCollection_FinishedOnAnotherReplica (0.00s)276=== CONT TestRegisterUploadedObjectReusesConnections277--- PASS: TestScriptTokenCachesUntilRefresh (0.03s)278=== CONT TestEncodeNixBase32/test_string_hash279=== CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)280=== CONT TestEncodeNixBase32/empty_input281--- PASS: TestEncodeNixBase32 (0.00s)282 --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)283 --- PASS: TestEncodeNixBase32/empty_input (0.00s)284=== CONT TestGetStorePathHash/valid_store_path285=== CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512286=== CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI287=== CONT TestPathInfoHashCompatibility/old_string_format_with_colon288--- PASS: TestPathInfoHashCompatibility (0.00s)289 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)290 --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)291 --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)292 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)293=== CONT TestConvertHashToNix32/SRI_format_to_Nix32294=== CONT TestGetStorePathHash/hash_with_wrong_length_should_error295=== CONT TestGetStorePathHash/hash_with_invalid_characters_should_error296=== CONT TestGetStorePathHash/basename_without_hyphen_should_error297--- PASS: TestGetStorePathHash (0.00s)298 --- PASS: TestGetStorePathHash/valid_store_path (0.00s)299 --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)300 --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)301 --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)302=== CONT TestConvertHashToNix32/already_Nix32_format303=== CONT TestRateLimiterFeedback/429_enables_limiter304--- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.03s)305=== CONT TestRateLimiterFeedback/400_does_not_enable_limiter3062026/10/06 11:18:21 WARN Rate limiter enabled after throttle name=server-test rate=53072026/10/06 11:18:21 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:546643082026/10/06 11:18:21 WARN Rate limiter backed off name=server-test rate=5309=== CONT TestRateLimiterFeedback/503_enables_limiter310=== CONT TestRateLimiterFeedback/200_does_not_enable_limiter3112026/10/06 11:18:21 WARN Rate limiter enabled after throttle name=server-test rate=53122026/10/06 11:18:21 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:546683132026/10/06 11:18:21 WARN Rate limiter backed off name=server-test rate=5314=== CONT TestConvertHashToNix32/invalid_format315--- PASS: TestConvertHashToNix32 (0.00s)316 --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)317 --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)318 --- PASS: TestConvertHashToNix32/invalid_format (0.00s)319=== CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths320--- PASS: TestRateLimiterFeedback (0.00s)321 --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)322 --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)323 --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)324 --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)325=== CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths326--- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)327 --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)328 --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)329=== CONT TestPathInfoCACompatibility/null_ca_field330=== CONT TestPathInfoCACompatibility/new_structured_format_-_text331=== CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive332=== CONT TestPathInfoCACompatibility/old_string_format_-_text333=== CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method334--- PASS: TestPathInfoCACompatibility (0.00s)335 --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)336 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)337 --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)338 --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)339 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)340=== CONT TestParsePathInfoJSON/whitespace_only341=== CONT TestParsePathInfoJSON/empty_input342=== CONT TestParsePathInfoJSON/Lix_format343=== CONT TestParsePathInfoJSON/invalid_JSON344=== CONT TestSetClientTLSErrors/missing_cert_file345=== CONT TestParsePathInfoJSON/Nix_format346--- PASS: TestParsePathInfoJSON (0.00s)347 --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)348 --- PASS: TestParsePathInfoJSON/empty_input (0.00s)349 --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)350 --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)351 --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)352=== CONT TestSetClientTLSErrors/missing_ca_file353=== CONT TestSetClientTLSErrors/invalid_ca_file354=== CONT TestSetClientTLSErrors/missing_key_file355=== CONT TestUploadMultipart_SupersededByPeer/exists356=== CONT TestPartSizeForNAR/zero_stays_at_minimum357=== CONT TestPartSizeForNAR/1_TiB358=== CONT TestPartSizeForNAR/capped_at_5_GiB359=== CONT TestPartSizeForNAR/5_TiB_S3_max_object360=== CONT TestPartSizeForNAR/80_GiB_fits_at_minimum361=== CONT TestPartSizeForNAR/115_GiB_needs_larger_parts362=== CONT TestPartSizeForNAR/small_stays_at_minimum363--- PASS: TestPartSizeForNAR (0.00s)364 --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)365 --- PASS: TestPartSizeForNAR/1_TiB (0.00s)366 --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)367 --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)368 --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)369 --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)370 --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)371=== CONT TestSetClientTLS/rejects_connection_without_client_cert372=== CONT TestUploadMultipart_SupersededByPeer/missing373--- PASS: TestSetClientTLSErrors (0.01s)374 --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)375 --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)376 --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)377 --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)378--- PASS: TestUploadMultipart_SupersededByPeer (0.00s)379 --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)380 --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)381=== CONT TestSetClientTLS/preserves_debug_logging_transport382=== CONT TestSetClientTLS/succeeds_with_client_cert_and_CA383--- PASS: TestDumpPathWriterError (0.04s)384=== CONT TestFilterOversizedClosures/no_limit_keeps_everything385=== CONT TestFilterOversizedClosures/all_closures_skipped3862026/10/06 11:18:21 WARN Skipping closure: path exceeds server max NAR size top_level_path=/nix/store/cccccccccccccccccccccccccccccccc-wrapper oversized_path=/nix/store/cccccccccccccccccccccccccccccccc-wrapper nar_size=100 max_nar_size=50387=== CONT TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped3882026/10/06 11:18:21 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=2000389--- PASS: TestFilterOversizedClosures (0.00s)390 --- PASS: TestFilterOversizedClosures/no_limit_keeps_everything (0.00s)391 --- PASS: TestFilterOversizedClosures/all_closures_skipped (0.00s)392 --- PASS: TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped (0.00s)393--- PASS: TestRegisterUploadedObjectReusesConnections (0.02s)3942026/10/06 11:18:21 http: TLS handshake error from 127.0.0.1:54674: remote error: tls: bad certificate395--- PASS: TestSetClientTLS (0.00s)396 --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)397 --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)398 --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.02s)399--- PASS: TestDumpPathSingleFile (0.05s)400--- PASS: TestCaseHackSuffix (0.04s)401--- PASS: TestDumpPathMatchesNix (0.06s)402--- PASS: TestStreamPushBatchesUnderLoad (0.10s)403--- PASS: TestUploadMultipart_PartsInParallel (0.61s)404--- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)405PASS406Running server tests...407The files belonging to this database system will be owned by user "_nixbld1".408This user must also own the server process.409410The database cluster will be initialized with locale "C".411The default database encoding has accordingly been set to "SQL_ASCII".412The default text search configuration will be set to "english".413414Data page checksums are enabled.415416creating directory /nix/var/nix/builds/nix-38141-2789532961/postgres2350274257/data ... ok417creating subdirectories ... ok418selecting dynamic shared memory implementation ... posix419selecting default "max_connections" ... 100420selecting default "shared_buffers" ... 128MB421selecting default time zone ... UTC422creating configuration files ... ok423running bootstrap script ... ok424performing post-bootstrap initialization ... ok425syncing data to disk ... ok426427initdb: warning: enabling "trust" authentication for local connections428initdb: 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.429430Success. You can now start the database server using:431432 pg_ctl -D /nix/var/nix/builds/nix-38141-2789532961/postgres2350274257/data -l logfile start433434/nix/var/nix/builds/nix-38141-2789532961/postgres2350274257:5432 - no response4352026-10-06 11:18:23.170 UTC [38184] LOG: starting PostgreSQL 18.6 on aarch64-apple-darwin25.6.0, compiled by clang version 21.1.8, 64-bit4362026-10-06 11:18:23.170 UTC [38184] LOG: listening on Unix socket "/nix/var/nix/builds/nix-38141-2789532961/postgres2350274257/.s.PGSQL.5432"4372026-10-06 11:18:23.203 UTC [38191] LOG: database system was shut down at 2026-10-06 11:18:23 UTC4382026-10-06 11:18:23.204 UTC [38184] LOG: database system is ready to accept connections439/nix/var/nix/builds/nix-38141-2789532961/postgres2350274257:5432 - accepting connections440{"timestamp":"2026-10-06T11:18:23.386199Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"6109f3b6-894f-4d33-832c-a828af5c60f8","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(3)"}441{"timestamp":"2026-10-06T11:18:23.490086Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"9f54d173-62d0-4f54-b9c0-e7dcfb3c4ce8","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)"}442=== RUN TestService_AuthMiddleware443=== PAUSE TestService_AuthMiddleware444=== RUN TestService_AuthMiddleware_MTLSProxyHeader445=== PAUSE TestService_AuthMiddleware_MTLSProxyHeader446=== RUN TestService_AuthMiddleware_MTLSBoundSubjects447=== PAUSE TestService_AuthMiddleware_MTLSBoundSubjects448=== RUN TestService_ReadAuthMiddleware449=== PAUSE TestService_ReadAuthMiddleware450=== RUN TestService_AuthMiddleware_OIDC451=== PAUSE TestService_AuthMiddleware_OIDC452=== RUN TestService_RequireScope_OIDC453=== PAUSE TestService_RequireScope_OIDC454=== RUN TestService_ReadScope_PublicByDefault455=== PAUSE TestService_ReadScope_PublicByDefault456=== RUN TestCacheConfigHandler457=== PAUSE TestCacheConfigHandler458=== RUN TestCacheStatsHandler459=== PAUSE TestCacheStatsHandler460=== RUN TestClientCADerivations461=== PAUSE TestClientCADerivations462=== RUN TestClientErrorHandling463=== PAUSE TestClientErrorHandling464=== RUN TestClientIntegration465=== PAUSE TestClientIntegration466=== RUN TestClientMultipleUploads467=== PAUSE TestClientMultipleUploads468=== RUN TestClientWithDependencies469=== PAUSE TestClientWithDependencies470=== RUN TestClientSharedPathCommittedMidPush471=== PAUSE TestClientSharedPathCommittedMidPush472=== RUN TestPinProtectsFromGC473=== PAUSE TestPinProtectsFromGC474=== RUN TestClientPushesUseOnePush475=== PAUSE TestClientPushesUseOnePush476=== RUN TestClientFallsBackToClosures477=== PAUSE TestClientFallsBackToClosures478=== RUN TestConcurrentCommitsSharingObjectsDoNotDeadlock479=== PAUSE TestConcurrentCommitsSharingObjectsDoNotDeadlock480=== RUN TestResolveDBConnectionString481=== PAUSE TestResolveDBConnectionString482=== RUN TestConnectWaitsForAPeerMigration483=== PAUSE TestConnectWaitsForAPeerMigration484=== RUN TestLeadElectsOneAndHandsOver485=== PAUSE TestLeadElectsOneAndHandsOver486=== RUN TestLeadIncumbentWinsAfterRestart4872026/10/06 11:18:23 INFO lead: acquired remote=192.0.2.1:12344882026/10/06 11:18:24 INFO lead: released remote=192.0.2.1:12344892026/10/06 11:18:24 INFO lead: acquired remote=192.0.2.1:12344902026/10/06 11:18:25 INFO lead: released remote=192.0.2.1:1234491--- PASS: TestLeadIncumbentWinsAfterRestart (1.84s)492=== RUN TestLeadIncumbentAnnouncesAtOnce493=== PAUSE TestLeadIncumbentAnnouncesAtOnce494=== RUN TestLeadEndsOnShutdown495=== PAUSE TestLeadEndsOnShutdown496=== RUN TestLeadEndsWhenItsConnectionHangs4972026/10/06 11:18:25 INFO lead: acquired remote=192.0.2.1:12344982026-10-06 11:18:26.803 UTC [38229] FATAL: terminating connection due to administrator command4992026/10/06 11:18:27 INFO lead: acquired remote=192.0.2.1:12345002026/10/06 11:18:28 WARN lead: lock connection lost error="timeout: context deadline exceeded"5012026/10/06 11:18:28 INFO lead: released remote=192.0.2.1:12345022026/10/06 11:18:28 INFO lead: released remote=192.0.2.1:1234503--- PASS: TestLeadEndsWhenItsConnectionHangs (2.77s)504=== RUN TestGCAdvisoryLockBlocksConcurrentRun505=== PAUSE TestGCAdvisoryLockBlocksConcurrentRun506=== RUN TestGCBugBareHashReferences507=== PAUSE TestGCBugBareHashReferences508=== RUN TestGCMetrics509=== PAUSE TestGCMetrics510=== RUN TestPushDedupSurvivesConcurrentGC511=== PAUSE TestPushDedupSurvivesConcurrentGC512=== RUN TestDeduplicatedObjectsRecordedAsPending513=== PAUSE TestDeduplicatedObjectsRecordedAsPending514=== RUN TestGCSweepSkipsPendingObjects515=== PAUSE TestGCSweepSkipsPendingObjects516=== RUN TestTombstonedObjectOfferedWithoutWaiting517=== PAUSE TestTombstonedObjectOfferedWithoutWaiting518=== RUN TestGCSweepDeliversEachKeyOnce519=== PAUSE TestGCSweepDeliversEachKeyOnce520=== RUN TestCreatePendingClosureVerifyS3FailureReleasesConnection521=== PAUSE TestCreatePendingClosureVerifyS3FailureReleasesConnection522=== RUN TestForceGCDuringPushOffersSweptObject523=== PAUSE TestForceGCDuringPushOffersSweptObject524=== RUN TestFailedS3DeleteKeepsTombstone525=== PAUSE TestFailedS3DeleteKeepsTombstone526=== RUN TestSweepSkipsRowsLockedByPush527=== PAUSE TestSweepSkipsRowsLockedByPush528=== RUN TestRegisterCompletedObjectNeedsPendingRow529=== PAUSE TestRegisterCompletedObjectNeedsPendingRow530=== RUN TestGetExistingObjectsLocksRows531=== PAUSE TestGetExistingObjectsLocksRows532=== RUN TestSweepRechecksPendingAfterLocking533=== PAUSE TestSweepRechecksPendingAfterLocking534=== RUN TestPushWaitsForSweepHoldingRow535=== PAUSE TestPushWaitsForSweepHoldingRow536=== RUN TestCommitRacingPendingCleanupKeepsObjects537=== PAUSE TestCommitRacingPendingCleanupKeepsObjects538=== RUN TestGCEndsOnShutdown539=== PAUSE TestGCEndsOnShutdown540=== RUN TestGCTaskStore_StartNew541=== PAUSE TestGCTaskStore_StartNew542=== RUN TestGCTaskStore_DeduplicateSameParams543=== PAUSE TestGCTaskStore_DeduplicateSameParams544=== RUN TestGCTaskStore_ConflictDifferentParams545=== PAUSE TestGCTaskStore_ConflictDifferentParams546=== RUN TestGCTaskStore_GetEmpty547=== PAUSE TestGCTaskStore_GetEmpty548=== RUN TestGCTaskStore_GetReturnsLatest549=== PAUSE TestGCTaskStore_GetReturnsLatest550=== RUN TestGCTaskStore_CompletedAllowsNewTask551=== PAUSE TestGCTaskStore_CompletedAllowsNewTask552=== RUN TestGCTaskStore_PhaseUpdates553=== PAUSE TestGCTaskStore_PhaseUpdates554=== RUN TestGCTaskStore_Fail555=== PAUSE TestGCTaskStore_Fail556=== RUN TestGracefulShutdownDrainsInflight557=== PAUSE TestGracefulShutdownDrainsInflight558=== RUN TestService_healthCheckHandler559=== PAUSE TestService_healthCheckHandler560=== RUN TestService_readinessHandler561=== PAUSE TestService_readinessHandler562=== RUN TestGenerateLandingPage563=== PAUSE TestGenerateLandingPage564=== RUN TestCacheConfigHandlerMaxNarSize565=== PAUSE TestCacheConfigHandlerMaxNarSize566=== RUN TestCreatePendingClosureRejectsOversizedNAR567=== PAUSE TestCreatePendingClosureRejectsOversizedNAR568=== RUN TestNARDeduplicationMetadataUploadBug569=== PAUSE TestNARDeduplicationMetadataUploadBug570=== RUN TestMetricsInventory571=== PAUSE TestMetricsInventory572=== RUN TestService_NativeMTLS573=== PAUSE TestService_NativeMTLS574=== RUN TestServerTLSConfig575=== PAUSE TestServerTLSConfig576=== RUN TestMultipartCleanup577=== PAUSE TestMultipartCleanup578=== RUN TestMultipartUploadAbortedWhenCancelledBeforeRecorded579=== PAUSE TestMultipartUploadAbortedWhenCancelledBeforeRecorded580=== RUN TestPendingCleanupUsesOneCutoff581=== PAUSE TestPendingCleanupUsesOneCutoff582=== RUN TestObjectStatsTrigger583=== PAUSE TestObjectStatsTrigger584=== RUN TestOrphanedObjectsGC585=== PAUSE TestOrphanedObjectsGC586=== RUN TestOrphanedObjectsGCStressTest587=== PAUSE TestOrphanedObjectsGCStressTest588=== RUN TestCreatePin_ReservedPins589=== PAUSE TestCreatePin_ReservedPins590=== RUN TestPresentReportsOnlyClosureRoots591=== PAUSE TestPresentReportsOnlyClosureRoots592=== RUN TestPresentNotReportedWhileGCDeletesClosure593=== PAUSE TestPresentNotReportedWhileGCDeletesClosure594=== RUN TestParseSingleRange595=== PAUSE TestParseSingleRange596=== RUN TestProxyHeadersOnlyTrustedOnSocket597=== PAUSE TestProxyHeadersOnlyTrustedOnSocket598=== RUN TestIsValidCachePath599=== PAUSE TestIsValidCachePath600=== RUN TestReadProxyNarinfo601=== PAUSE TestReadProxyNarinfo602=== RUN TestReadProxyNarinfoAlreadyDecompressed603=== PAUSE TestReadProxyNarinfoAlreadyDecompressed604=== RUN TestReadProxyContentNegotiation605=== PAUSE TestReadProxyContentNegotiation606=== RUN TestReadProxyNarStreaming607=== PAUSE TestReadProxyNarStreaming608=== RUN TestReadProxy404609=== PAUSE TestReadProxy404610=== RUN TestReadProxyInvalidPath611=== PAUSE TestReadProxyInvalidPath612=== RUN TestReadProxyHead613=== PAUSE TestReadProxyHead614=== RUN TestReadProxyOutlastsServerWriteTimeout615=== PAUSE TestReadProxyOutlastsServerWriteTimeout616=== RUN TestReadProxyConditionalGet617=== PAUSE TestReadProxyConditionalGet618=== RUN TestReadProxyRootRedirectsToIndexHTML619=== PAUSE TestReadProxyRootRedirectsToIndexHTML620=== RUN TestReadProxyDisabled621=== PAUSE TestReadProxyDisabled622=== RUN TestReadRedirectNar623=== PAUSE TestReadRedirectNar624=== RUN TestReadRedirectKeepsNarinfoProxied625=== PAUSE TestReadRedirectKeepsNarinfoProxied626=== RUN TestReadProxyRangeRequest627=== PAUSE TestReadProxyRangeRequest628=== RUN TestReadRedirectUsesPublicS3URL629=== PAUSE TestReadRedirectUsesPublicS3URL630=== RUN TestPush_OverlappingRootsStoreOneRowPerKey631=== PAUSE TestPush_OverlappingRootsStoreOneRowPerKey632=== RUN TestPush_CompleteCommitsEveryRoot633=== PAUSE TestPush_CompleteCommitsEveryRoot634=== RUN TestPush_SkippedKeySurvivesGCBeforeCommit635=== PAUSE TestPush_SkippedKeySurvivesGCBeforeCommit636=== RUN TestPush_RejectsBadRequests637=== PAUSE TestPush_RejectsBadRequests638=== RUN TestPush_SignsNarinfosOfItsPendingObjects639=== PAUSE TestPush_SignsNarinfosOfItsPendingObjects640=== RUN TestRedundantMultipartUpload641=== PAUSE TestRedundantMultipartUpload642=== RUN TestCompleteMultipartUpload_ErrorButObjectExists643=== PAUSE TestCompleteMultipartUpload_ErrorButObjectExists644=== RUN TestCompletedNarNotReofferedAcrossClosures645=== PAUSE TestCompletedNarNotReofferedAcrossClosures646=== RUN TestPresignedUploadRegisteredBeforeCommit647=== PAUSE TestPresignedUploadRegisteredBeforeCommit648=== RUN TestService_Rustfstest649=== PAUSE TestService_Rustfstest650=== RUN TestParseSize651=== PAUSE TestParseSize652=== RUN TestSkippedUploadsHandler653=== PAUSE TestSkippedUploadsHandler654=== RUN TestSystemdListenerNotActivated655--- PASS: TestSystemdListenerNotActivated (0.00s)656=== RUN TestWatchdogBeatsWhenHealthy657--- PASS: TestWatchdogBeatsWhenHealthy (0.02s)658=== RUN TestWatchdogSkipsWhenUnhealthy6592026/10/06 11:18:28 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6602026/10/06 11:18:28 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6612026/10/06 11:18:28 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6622026/10/06 11:18:28 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6632026/10/06 11:18:28 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6642026/10/06 11:18:28 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6652026/10/06 11:18:28 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6662026/10/06 11:18:28 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6672026/10/06 11:18:28 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6682026/10/06 11:18:28 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"669--- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)670=== RUN TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle671=== PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle672=== RUN TestProxyWriteTimeout673=== PAUSE TestProxyWriteTimeout674=== RUN TestPendingClosureWriteTimeout675=== PAUSE TestPendingClosureWriteTimeout676=== RUN TestIsValidUploadKey677=== PAUSE TestIsValidUploadKey678=== RUN TestUploadHandlersRejectInvalidKeys679=== PAUSE TestUploadHandlersRejectInvalidKeys680=== RUN TestUploadHandlersRejectOversizedBody681=== PAUSE TestUploadHandlersRejectOversizedBody682=== RUN TestService_cleanupPendingClosuresHandler683=== PAUSE TestService_cleanupPendingClosuresHandler684=== RUN TestService_createPendingClosureHandler685=== PAUSE TestService_createPendingClosureHandler686=== RUN TestService_verifyS3Integrity687=== PAUSE TestService_verifyS3Integrity688=== RUN TestCompleteMultipartUnregistered689=== PAUSE TestCompleteMultipartUnregistered690=== RUN TestCreatePendingClosure_SmallNARUsesSimplePUT691=== PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT692=== CONT TestReadProxy404693=== CONT TestFailedS3DeleteKeepsTombstone694=== CONT TestRegisterCompletedObjectNeedsPendingRow695=== CONT TestCacheConfigHandlerMaxNarSize696=== CONT TestService_AuthMiddleware697=== CONT TestReadProxyNarStreaming698=== CONT TestReadProxyContentNegotiation699=== CONT TestReadProxyNarinfoAlreadyDecompressed700=== CONT TestReadProxyNarinfo701=== CONT TestSweepSkipsRowsLockedByPush702--- PASS: TestCacheConfigHandlerMaxNarSize (0.00s)703=== CONT TestCompleteMultipartUpload_ErrorButObjectExists7042026/10/06 11:18:28 INFO Aborted multipart uploads count=0 kept=07052026/10/06 11:18:28 WARN Force mode enabled - objects will be deleted immediately without grace period7062026/10/06 11:18:28 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=07072026/10/06 11:18:28 INFO Vacuumed table table=pending_closures7082026/10/06 11:18:28 INFO Vacuumed table table=pending_objects7092026/10/06 11:18:28 INFO Vacuumed table table=multipart_uploads7102026/10/06 11:18:28 INFO Vacuumed table table=closures7112026/10/06 11:18:28 INFO Vacuumed table table=objects7122026/10/06 11:18:28 INFO Aborted multipart uploads count=0 kept=07132026/10/06 11:18:28 WARN Force mode enabled - objects will be deleted immediately without grace period7142026/10/06 11:18:28 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=1 objects-failed-to-delete=07152026/10/06 11:18:28 INFO Vacuumed table table=pending_closures7162026/10/06 11:18:28 INFO Vacuumed table table=pending_objects7172026/10/06 11:18:28 INFO Vacuumed table table=multipart_uploads7182026/10/06 11:18:28 INFO Vacuumed table table=closures7192026/10/06 11:18:28 INFO Vacuumed table table=objects720--- PASS: TestSweepSkipsRowsLockedByPush (0.50s)721=== CONT TestCreatePendingClosure_SmallNARUsesSimplePUT722--- PASS: TestReadProxy404 (0.65s)723=== CONT TestCompleteMultipartUnregistered724--- PASS: TestReadProxyNarStreaming (0.76s)725=== CONT TestService_verifyS3Integrity7262026/10/06 11:18:29 ERROR Failed to decode narinfo key=3hcdxyjf9yiq7qf3i4548drb6sjmwa1v.narinfo limit=16777216 error=<nil>727--- PASS: TestReadProxyNarinfo (0.99s)728=== CONT TestService_createPendingClosureHandler7292026/10/06 11:18:29 INFO Received uploads request method=POST path=/api/pending_closures7302026/10/06 11:18:29 INFO Aborted multipart uploads count=0 kept=07312026/10/06 11:18:29 WARN Force mode enabled - objects will be deleted immediately without grace period7322026/10/06 11:18:29 ERROR failed to remove object object=nar/dddddddddddddddddddddddddddddddddddddddddddddddddddd.nar.zst error="Access Denied."7332026/10/06 11:18:29 ERROR failed to remove object object=dddddddddddddddddddddddddddddddd.narinfo error="Access Denied."734--- PASS: TestFailedS3DeleteKeepsTombstone (1.24s)735=== CONT TestService_cleanupPendingClosuresHandler736--- PASS: TestRegisterCompletedObjectNeedsPendingRow (1.30s)737=== CONT TestUploadHandlersRejectOversizedBody738=== RUN TestUploadHandlersRejectOversizedBody/create_pending_closure739=== PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure740=== RUN TestUploadHandlersRejectOversizedBody/complete_multipart741=== PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart742=== RUN TestUploadHandlersRejectOversizedBody/request_more_parts743=== PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts744=== CONT TestUploadHandlersRejectInvalidKeys745=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info746=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info747=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal748=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal749=== RUN TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key750=== PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key751=== RUN TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key752=== PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key753=== CONT TestIsValidUploadKey754=== RUN TestIsValidUploadKey/narinfo755=== PAUSE TestIsValidUploadKey/narinfo756=== RUN TestIsValidUploadKey/nar_zst757=== PAUSE TestIsValidUploadKey/nar_zst758=== RUN TestIsValidUploadKey/nar_xz759=== PAUSE TestIsValidUploadKey/nar_xz760=== RUN TestIsValidUploadKey/nar_plain761=== PAUSE TestIsValidUploadKey/nar_plain762=== RUN TestIsValidUploadKey/listing763=== PAUSE TestIsValidUploadKey/listing764=== RUN TestIsValidUploadKey/build_log765=== PAUSE TestIsValidUploadKey/build_log766=== RUN TestIsValidUploadKey/build_log_home-manager_file767=== PAUSE TestIsValidUploadKey/build_log_home-manager_file768=== RUN TestIsValidUploadKey/build_log_plus_in_name769=== PAUSE TestIsValidUploadKey/build_log_plus_in_name770=== RUN TestIsValidUploadKey/build_log_question_mark771=== PAUSE TestIsValidUploadKey/build_log_question_mark772=== RUN TestIsValidUploadKey/build_log_equals773=== PAUSE TestIsValidUploadKey/build_log_equals774=== RUN TestIsValidUploadKey/realisation775=== PAUSE TestIsValidUploadKey/realisation776=== RUN TestIsValidUploadKey/realisation_plus_in_output777=== PAUSE TestIsValidUploadKey/realisation_plus_in_output778=== RUN TestIsValidUploadKey/nix-cache-info779=== PAUSE TestIsValidUploadKey/nix-cache-info780=== RUN TestIsValidUploadKey/index.html781=== PAUSE TestIsValidUploadKey/index.html782=== RUN TestIsValidUploadKey/narinfo_key,_nar_type783=== PAUSE TestIsValidUploadKey/narinfo_key,_nar_type784=== RUN TestIsValidUploadKey/nar_key,_narinfo_type785=== PAUSE TestIsValidUploadKey/nar_key,_narinfo_type786=== RUN TestIsValidUploadKey/listing_key,_narinfo_type787=== PAUSE TestIsValidUploadKey/listing_key,_narinfo_type788=== RUN TestIsValidUploadKey/traversal789=== PAUSE TestIsValidUploadKey/traversal790=== RUN TestIsValidUploadKey/traversal_nar791=== PAUSE TestIsValidUploadKey/traversal_nar792=== RUN TestIsValidUploadKey/absolute793=== PAUSE TestIsValidUploadKey/absolute794=== RUN TestIsValidUploadKey/empty_key795=== PAUSE TestIsValidUploadKey/empty_key796=== RUN TestIsValidUploadKey/unknown_type797=== PAUSE TestIsValidUploadKey/unknown_type798=== CONT TestPendingClosureWriteTimeout799=== RUN TestPendingClosureWriteTimeout/empty800=== PAUSE TestPendingClosureWriteTimeout/empty801=== RUN TestPendingClosureWriteTimeout/400_objects802=== PAUSE TestPendingClosureWriteTimeout/400_objects803=== RUN TestPendingClosureWriteTimeout/670k_objects804=== PAUSE TestPendingClosureWriteTimeout/670k_objects805=== RUN TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline806=== PAUSE TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline807=== CONT TestProxyWriteTimeout808=== RUN TestProxyWriteTimeout/narinfo809=== PAUSE TestProxyWriteTimeout/narinfo810=== RUN TestProxyWriteTimeout/1_GiB_nar811=== PAUSE TestProxyWriteTimeout/1_GiB_nar812=== RUN TestProxyWriteTimeout/10_GiB_nar813=== PAUSE TestProxyWriteTimeout/10_GiB_nar814=== RUN TestProxyWriteTimeout/unknown_size815=== PAUSE TestProxyWriteTimeout/unknown_size816=== CONT TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle8172026/10/06 11:18:29 INFO Received uploads request method=POST path=/api/pending_closures8182026/10/06 11:18:30 INFO Received complete multipart upload request method=POST path=/api/multipart/complete8192026/10/06 11:18:30 WARN CompleteMultipartUpload errored but object exists; treating as success error="The specified key does not exist." object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=OTAzZDQxNjItOGIyOC00NjQzLTk0YWMtYTZmYTAzNGY5ZDQ3LjJlNzgzYjY1LTJmYWEtNGE2ZS04MjM1LTkxZmY4NzlkNjJmOXgxNzkxMjg1NTA5OTQzOTkxMDAw8202026/10/06 11:18:30 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=OTAzZDQxNjItOGIyOC00NjQzLTk0YWMtYTZmYTAzNGY5ZDQ3LjJlNzgzYjY1LTJmYWEtNGE2ZS04MjM1LTkxZmY4NzlkNjJmOXgxNzkxMjg1NTA5OTQzOTkxMDAw parts=1821--- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (1.70s)822=== CONT TestSkippedUploadsHandler8232026/10/06 11:18:30 INFO Client skipped oversized paths paths=3 nar_bytes=5000000000824--- PASS: TestReadProxyNarinfoAlreadyDecompressed (1.70s)825=== CONT TestParseSize826--- PASS: TestParseSize (0.00s)827=== CONT TestService_Rustfstest828=== CONT TestPresignedUploadRegisteredBeforeCommit829--- PASS: TestSkippedUploadsHandler (0.00s)830--- PASS: TestReadProxyContentNegotiation (1.94s)831=== CONT TestCompletedNarNotReofferedAcrossClosures8322026/10/06 11:18:30 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"833--- PASS: TestService_AuthMiddleware (2.06s)834=== CONT TestObjectStatsTrigger8352026/10/06 11:18:30 INFO Received uploads request method=POST path=/api/pending_closures836--- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (1.86s)837=== CONT TestIsValidCachePath838=== RUN TestIsValidCachePath/narinfo839=== PAUSE TestIsValidCachePath/narinfo840=== RUN TestIsValidCachePath/narinfo_all_nix_base32_chars841=== PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars842=== RUN TestIsValidCachePath/nar_zst843=== PAUSE TestIsValidCachePath/nar_zst844=== RUN TestIsValidCachePath/nar_xz845=== PAUSE TestIsValidCachePath/nar_xz846=== RUN TestIsValidCachePath/nar_bz2847=== PAUSE TestIsValidCachePath/nar_bz2848=== RUN TestIsValidCachePath/nar_uncompressed849=== PAUSE TestIsValidCachePath/nar_uncompressed850=== RUN TestIsValidCachePath/ls851=== PAUSE TestIsValidCachePath/ls852=== RUN TestIsValidCachePath/log853=== PAUSE TestIsValidCachePath/log854=== RUN TestIsValidCachePath/realisation855=== PAUSE TestIsValidCachePath/realisation856=== RUN TestIsValidCachePath/nix-cache-info857=== PAUSE TestIsValidCachePath/nix-cache-info858=== RUN TestIsValidCachePath/index.html859=== PAUSE TestIsValidCachePath/index.html860=== RUN TestIsValidCachePath/traversal_parent861=== PAUSE TestIsValidCachePath/traversal_parent862=== RUN TestIsValidCachePath/traversal_in_middle863=== PAUSE TestIsValidCachePath/traversal_in_middle864=== RUN TestIsValidCachePath/invalid_char_e865=== PAUSE TestIsValidCachePath/invalid_char_e866=== RUN TestIsValidCachePath/invalid_char_u867=== PAUSE TestIsValidCachePath/invalid_char_u868=== RUN TestIsValidCachePath/random_path869=== PAUSE TestIsValidCachePath/random_path870=== RUN TestIsValidCachePath/empty871=== PAUSE TestIsValidCachePath/empty872=== RUN TestIsValidCachePath/leading_slash873=== PAUSE TestIsValidCachePath/leading_slash874=== RUN TestIsValidCachePath/wrong_extension875=== PAUSE TestIsValidCachePath/wrong_extension876=== RUN TestIsValidCachePath/short_hash877=== PAUSE TestIsValidCachePath/short_hash878=== CONT TestProxyHeadersOnlyTrustedOnSocket8792026/10/06 11:18:30 INFO Received complete multipart upload request method=POST path=/api/multipart/complete8802026/10/06 11:18:30 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst881--- PASS: TestCompleteMultipartUnregistered (1.81s)882=== CONT TestParseSingleRange883=== RUN TestParseSingleRange/none884=== PAUSE TestParseSingleRange/none885=== RUN TestParseSingleRange/unknown_unit886=== PAUSE TestParseSingleRange/unknown_unit887=== RUN TestParseSingleRange/multi-range_ignored888=== PAUSE TestParseSingleRange/multi-range_ignored889=== RUN TestParseSingleRange/malformed_no_dash890=== PAUSE TestParseSingleRange/malformed_no_dash891=== RUN TestParseSingleRange/malformed_both_empty892=== PAUSE TestParseSingleRange/malformed_both_empty893=== RUN TestParseSingleRange/malformed_end_before_start894=== PAUSE TestParseSingleRange/malformed_end_before_start895=== RUN TestParseSingleRange/closed896=== PAUSE TestParseSingleRange/closed897=== RUN TestParseSingleRange/open-ended898=== PAUSE TestParseSingleRange/open-ended899=== RUN TestParseSingleRange/end_clamped_to_size900=== PAUSE TestParseSingleRange/end_clamped_to_size901=== RUN TestParseSingleRange/suffix902=== PAUSE TestParseSingleRange/suffix903=== RUN TestParseSingleRange/suffix_exceeds_size904=== PAUSE TestParseSingleRange/suffix_exceeds_size905=== RUN TestParseSingleRange/single_byte906=== PAUSE TestParseSingleRange/single_byte907=== RUN TestParseSingleRange/start_past_EOF908=== PAUSE TestParseSingleRange/start_past_EOF909=== RUN TestParseSingleRange/start_far_past_EOF910=== PAUSE TestParseSingleRange/start_far_past_EOF911=== CONT TestPresentNotReportedWhileGCDeletesClosure9122026/10/06 11:18:31 INFO Received uploads request method=POST path=/api/pending_closures9132026/10/06 11:18:31 INFO Received uploads request method=POST path=/api/pending_closures9142026/10/06 11:18:31 INFO Received uploads request method=POST path=/api/pending_closures9152026/10/06 11:18:31 INFO Received uploads request method=POST path=/api/pending_closures9162026/10/06 11:18:31 INFO Received cleanup request method=DELETE path=/api/pending_closures9172026/10/06 11:18:31 INFO Aborted multipart uploads count=0 kept=09182026/10/06 11:18:31 INFO Received uploads request method=POST path=/api/pending_closures9192026/10/06 11:18:31 INFO Received cleanup request method=DELETE path=/api/pending_closures9202026/10/06 11:18:31 INFO Aborted multipart uploads count=1 kept=09212026/10/06 11:18:31 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete9222026-10-06 11:18:31.743 UTC [38291] ERROR: Closure does not exist: id=19232026-10-06 11:18:31.743 UTC [38291] CONTEXT: PL/pgSQL function commit_pending_closure(bigint) line 12 at RAISE9242026-10-06 11:18:31.743 UTC [38291] STATEMENT: -- name: CommitPendingClosure :exec925 SELECT commit_pending_closure($1::bigint)926 927--- PASS: TestService_cleanupPendingClosuresHandler (2.07s)928=== CONT TestPresentReportsOnlyClosureRoots9292026/10/06 11:18:31 INFO Received uploads request method=POST path=/api/pending_closures930--- PASS: TestService_Rustfstest (2.19s)931=== CONT TestCreatePin_ReservedPins9322026/10/06 11:18:32 INFO Received complete multipart upload request method=POST path=/api/multipart/complete9332026/10/06 11:18:32 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:54759/oidc9342026/10/06 11:18:32 INFO Received complete multipart upload request method=POST path=/api/multipart/complete9352026/10/06 11:18:32 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=OTAzZDQxNjItOGIyOC00NjQzLTk0YWMtYTZmYTAzNGY5ZDQ3LjZiYzVmNzVkLWI0NTQtNGZjMS1iNTdmLTdmNjZlMDg1NmRhNXgxNzkxMjg1NTExMTExNzc0MDAw parts=109362026/10/06 11:18:32 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete9372026/10/06 11:18:32 INFO Completed upload id=19382026/10/06 11:18:32 INFO Received uploads request method=POST path=/api/pending_closures9392026/10/06 11:18:32 INFO Received uploads request method=POST path=/api/pending_closures9402026/10/06 11:18:32 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo9412026/10/06 11:18:32 WARN Found objects in DB but missing from S3, will re-upload count=1942--- PASS: TestService_verifyS3Integrity (3.44s)943=== CONT TestOrphanedObjectsGCStressTest9442026/10/06 11:18:32 INFO Received uploads request method=POST path=/api/pending_closures9452026/10/06 11:18:32 INFO Registered completed upload object_key=nar/jjjjjjjjjjjjjjjjjjjjjjjjjjjjjj0100000000000000000000.nar.zst9462026/10/06 11:18:32 INFO Received uploads request method=POST path=/api/pending_closures947--- PASS: TestPresignedUploadRegisteredBeforeCommit (2.56s)948=== CONT TestOrphanedObjectsGC9492026/10/06 11:18:32 INFO Received complete multipart upload request method=POST path=/api/multipart/complete9502026/10/06 11:18:32 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=OTAzZDQxNjItOGIyOC00NjQzLTk0YWMtYTZmYTAzNGY5ZDQ3LjgyODBmZTM5LWY4YTgtNDY4Yi1hMThjLTA5MmU0MDRkNTgyZngxNzkxMjg1NTExMzczNTcyMDAw parts=109512026/10/06 11:18:32 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete9522026/10/06 11:18:32 INFO Received uploads request method=POST path=/api/pending_closures9532026/10/06 11:18:32 INFO Completed upload id=19542026/10/06 11:18:32 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000009552026/10/06 11:18:32 INFO Received uploads request method=POST path=/api/pending_closures9562026/10/06 11:18:32 INFO Starting cleanup of old closures method=DELETE path=/api/closures9572026/10/06 11:18:32 INFO Aborted multipart uploads count=0 kept=09582026/10/06 11:18:32 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=09592026/10/06 11:18:32 INFO Vacuumed table table=pending_closures9602026/10/06 11:18:32 INFO Vacuumed table table=pending_objects9612026/10/06 11:18:32 INFO Vacuumed table table=multipart_uploads9622026/10/06 11:18:32 INFO Vacuumed table table=closures9632026/10/06 11:18:33 INFO Vacuumed table table=objects9642026/10/06 11:18:33 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000965--- PASS: TestService_createPendingClosureHandler (3.59s)966=== CONT TestServerTLSConfig967=== RUN TestServerTLSConfig/no_client_CA968=== PAUSE TestServerTLSConfig/no_client_CA969=== RUN TestServerTLSConfig/missing_CA_file970=== PAUSE TestServerTLSConfig/missing_CA_file971=== RUN TestServerTLSConfig/not_a_PEM_file972=== PAUSE TestServerTLSConfig/not_a_PEM_file973=== CONT TestPendingCleanupUsesOneCutoff974--- PASS: TestObjectStatsTrigger (2.70s)975=== CONT TestMultipartUploadAbortedWhenCancelledBeforeRecorded9762026/10/06 11:18:33 INFO Starting HTTP server address=127.0.0.1:547699772026/10/06 11:18:33 INFO Starting HTTP server address=/nix/var/nix/builds/nix-38141-2789532961/TestProxyHeadersOnlyTrustedOnSocket3678622786/001/proxy.sock9782026/10/06 11:18:33 WARN mTLS auth: subject not in bound subjects subject="CN=someone"9792026/10/06 11:18:33 INFO Shutdown signal received, draining in-flight requests timeout=10s980--- PASS: TestProxyHeadersOnlyTrustedOnSocket (2.58s)981=== CONT TestMultipartCleanup982--- PASS: TestPresentReportsOnlyClosureRoots (2.28s)983=== CONT TestReadProxyRangeRequest984--- PASS: TestPresentNotReportedWhileGCDeletesClosure (3.20s)985=== CONT TestRedundantMultipartUpload9862026/10/06 11:18:34 INFO Received complete multipart upload request method=POST path=/api/multipart/complete9872026/10/06 11:18:34 INFO Completed multipart upload object_key=nar/hhhhhhhhhhhhhhhhhhhhhhhhhhhhhh0100000000000000000000.nar.zst upload_id=OTAzZDQxNjItOGIyOC00NjQzLTk0YWMtYTZmYTAzNGY5ZDQ3LmEwNTcxNzE5LTUxMjItNDZlNC04Yjg1LTY2ZjkzYzc3MzU0MngxNzkxMjg1NTEyOTI5MDg5MDAw parts=129882026/10/06 11:18:34 INFO Received uploads request method=POST path=/api/pending_closures989--- PASS: TestCompletedNarNotReofferedAcrossClosures (3.90s)990=== CONT TestPush_SignsNarinfosOfItsPendingObjects9912026/10/06 11:18:34 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux9922026/10/06 11:18:34 WARN Refused reserved pin name=worker-x86_64-linux9932026/10/06 11:18:34 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux9942026/10/06 11:18:34 INFO Received create pin request method=POST path=/api/pins/my-app9952026/10/06 11:18:34 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux996--- PASS: TestCreatePin_ReservedPins (2.15s)997=== CONT TestPush_RejectsBadRequests9982026/10/06 11:18:35 INFO Received uploads request method=POST path=/api/pending_closures9992026/10/06 11:18:35 INFO Received uploads request method=POST path=/api/pending_closures10002026/10/06 11:18:35 INFO Received cleanup request method=DELETE path=/api/pending_closures1001=== NAME TestOrphanedObjectsGC1002 orphaned_objects_gc_test.go:290: GC Test Summary:1003 orphaned_objects_gc_test.go:291: - Kept: 2 objects from closure A1004 orphaned_objects_gc_test.go:292: - Deleted: 2 objects from closure B1005 orphaned_objects_gc_test.go:293: - Deleted: 6 orphaned chain objects (X1->X2->X3)1006 orphaned_objects_gc_test.go:294: - Deleted: 2 orphaned single objects (Y)1007 orphaned_objects_gc_test.go:295: - Total deleted: 10 objects1008--- PASS: TestOrphanedObjectsGC (2.97s)1009=== CONT TestPush_SkippedKeySurvivesGCBeforeCommit10102026/10/06 11:18:35 INFO Received uploads request method=POST path=/api/pending_closures1011--- PASS: TestMultipartUploadAbortedWhenCancelledBeforeRecorded (2.62s)1012=== CONT TestPush_CompleteCommitsEveryRoot10132026/10/06 11:18:36 WARN Rate limiter enabled after throttle name=s3-test rate=510142026/10/06 11:18:36 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1015=== NAME TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1016 throttle_test.go:213: Proxy stats: total=13, throttled=10, completeMultipart=101017 throttle_test.go:215: Rate limiter: enabled=true, rate=5.001018--- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (6.31s)1019=== CONT TestPush_OverlappingRootsStoreOneRowPerKey10202026/10/06 11:18:36 INFO Received uploads request method=POST path=/api/pending_closures10212026/10/06 11:18:36 INFO Received cleanup request method=DELETE path=/api/pending_closures10222026/10/06 11:18:36 WARN Failed to abort upload, keeping its closure key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst error="Get \"http://127.0.0.1:1/bucket31/?location=\": dial tcp 127.0.0.1:1: connect: connection refused" code=""10232026/10/06 11:18:36 INFO Aborted multipart uploads count=0 kept=110242026/10/06 11:18:36 INFO Received cleanup request method=DELETE path=/api/pending_closures10252026/10/06 11:18:36 INFO Aborted multipart uploads count=1 kept=01026--- PASS: TestMultipartCleanup (2.97s)1027=== CONT TestReadRedirectUsesPublicS3URL1028--- PASS: TestReadProxyRangeRequest (2.96s)1029=== CONT TestMetricsInventory10302026/10/06 11:18:37 INFO Received uploads request method=POST path=/api/pending_closures10312026/10/06 11:18:37 INFO Received uploads request method=POST path=/api/pending_closures10322026/10/06 11:18:37 INFO Received push request method=POST path=/api/pushes10332026/10/06 11:18:37 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign10342026/10/06 11:18:37 INFO Signed narinfos id=1 count=110352026/10/06 11:18:37 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign10362026/10/06 11:18:37 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign10372026/10/06 11:18:37 ERROR Failed to sign narinfos signer="index 1" error="signing key cannot be nil"10382026/10/06 11:18:37 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign10392026/10/06 11:18:37 ERROR Failed to sign narinfos signer=niks3-test-1 error="signer failed"10402026/10/06 11:18:37 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign10412026/10/06 11:18:37 ERROR Signer returned unexpected signature count signer=niks3-test-1 got=0 want=110422026/10/06 11:18:37 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign10432026/10/06 11:18:37 ERROR Signer returned unexpected object key signer=niks3-test-1 object_key=unexpected.narinfo1044--- PASS: TestPush_SignsNarinfosOfItsPendingObjects (3.59s)1045=== CONT TestService_NativeMTLS1046=== RUN TestPush_RejectsBadRequests/no_objects1047=== PAUSE TestPush_RejectsBadRequests/no_objects1048=== RUN TestPush_RejectsBadRequests/bad_root1049=== PAUSE TestPush_RejectsBadRequests/bad_root1050=== RUN TestPush_RejectsBadRequests/root_not_in_objects1051=== PAUSE TestPush_RejectsBadRequests/root_not_in_objects1052=== RUN TestPush_RejectsBadRequests/no_roots1053=== PAUSE TestPush_RejectsBadRequests/no_roots1054=== CONT TestReadProxyRootRedirectsToIndexHTML10552026/10/06 11:18:39 INFO Received push request method=POST path=/api/pushes10562026/10/06 11:18:39 INFO Received complete push request method=POST path=/api/pushes/1/complete10572026/10/06 11:18:39 INFO Received push request method=POST path=/api/pushes10582026/10/06 11:18:39 INFO Aborted multipart uploads count=0 kept=010592026/10/06 11:18:39 WARN Force mode enabled - objects will be deleted immediately without grace period10602026/10/06 11:18:39 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=010612026/10/06 11:18:39 INFO Vacuumed table table=pending_closures10622026/10/06 11:18:39 INFO Received complete multipart upload request method=POST path=/api/multipart/complete10632026/10/06 11:18:39 INFO Vacuumed table table=pending_objects10642026/10/06 11:18:39 INFO Vacuumed table table=multipart_uploads10652026/10/06 11:18:39 INFO Vacuumed table table=closures10662026/10/06 11:18:39 INFO Received push request method=POST path=/api/pushes10672026/10/06 11:18:39 INFO Vacuumed table table=objects10682026/10/06 11:18:39 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=OTAzZDQxNjItOGIyOC00NjQzLTk0YWMtYTZmYTAzNGY5ZDQ3LjQ1MjFhODdiLTgxN2EtNDY2Mi1iOGIyLThhZDJlZGI2MjQ2Y3gxNzkxMjg1NTE3MzM1NzE0MDAw parts=1210692026/10/06 11:18:39 INFO Received complete push request method=POST path=/api/pushes/2/complete10702026/10/06 11:18:39 INFO Received uploads request method=POST path=/api/pending_closures1071--- PASS: TestPush_SkippedKeySurvivesGCBeforeCommit (3.83s)1072=== CONT TestReadRedirectKeepsNarinfoProxied10732026/10/06 11:18:39 INFO Received uploads request method=POST path=/api/pending_closures10742026/10/06 11:18:39 INFO Received complete push request method=POST path=/api/pushes/1/complete1075--- PASS: TestPush_CompleteCommitsEveryRoot (3.79s)1076=== CONT TestReadRedirectNar10772026/10/06 11:18:39 INFO Received push request method=POST path=/api/pushes1078--- PASS: TestPush_OverlappingRootsStoreOneRowPerKey (3.86s)1079=== CONT TestReadProxyDisabled1080--- PASS: TestReadRedirectUsesPublicS3URL (3.90s)1081=== CONT TestNARDeduplicationMetadataUploadBug1082--- PASS: TestMetricsInventory (3.85s)1083=== CONT TestReadProxyOutlastsServerWriteTimeout10842026/10/06 11:18:41 WARN mTLS auth: subject not in bound subjects subject="CN=reader"10852026/10/06 11:18:41 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1086--- PASS: TestService_NativeMTLS (3.23s)1087=== CONT TestReadProxyConditionalGet10882026/10/06 11:18:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1089--- PASS: TestReadProxyRootRedirectsToIndexHTML (3.34s)1090=== CONT TestCreatePendingClosureRejectsOversizedNAR10912026/10/06 11:18:41 INFO Received uploads request method=POST path=/api/pending_closures1092--- PASS: TestCreatePendingClosureRejectsOversizedNAR (0.00s)1093=== CONT TestReadProxyHead10942026/10/06 11:18:41 INFO Aborted multipart uploads count=1 kept=010952026/10/06 11:18:41 INFO Received cleanup request method=DELETE path=/api/pending_closures10962026/10/06 11:18:41 INFO Aborted multipart uploads count=1 kept=010972026/10/06 11:18:41 WARN Failed to abort redundant multipart upload, keeping its row object_key=nar/gggggggggggggggggggggggggggggg0200000000000000000000.nar.zst upload_id=OTAzZDQxNjItOGIyOC00NjQzLTk0YWMtYTZmYTAzNGY5ZDQ3LmY3OTQwZDUxLTc0NjMtNGJhNi1iZDZjLTA5NmNhODM0MjI5MHgxNzkxMjg1NTE5NTU5MDI0MDAw error="Get \"http://127.0.0.1:1/bucket33/?location=\": dial tcp 127.0.0.1:1: connect: connection refused"10982026/10/06 11:18:41 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0200000000000000000000.nar.zst upload_id=OTAzZDQxNjItOGIyOC00NjQzLTk0YWMtYTZmYTAzNGY5ZDQ3LjNjYTBjMjNmLTRiODQtNDk2OS05ODUzLWVmMjk1ZjczMDNiOXgxNzkxMjg1NTE5NDkyNDUyMDAw parts=121099--- PASS: TestPendingCleanupUsesOneCutoff (8.53s)1100=== CONT TestClientFallsBackToClosures11012026/10/06 11:18:41 INFO Received cleanup request method=DELETE path=/api/pending_closures11022026/10/06 11:18:41 INFO Aborted multipart uploads count=1 kept=01103--- PASS: TestRedundantMultipartUpload (7.48s)1104=== CONT TestForceGCDuringPushOffersSweptObject1105=== RUN TestForceGCDuringPushOffersSweptObject/before_pending_rows1106=== PAUSE TestForceGCDuringPushOffersSweptObject/before_pending_rows1107=== RUN TestForceGCDuringPushOffersSweptObject/after_presence_check1108=== PAUSE TestForceGCDuringPushOffersSweptObject/after_presence_check1109=== CONT TestCreatePendingClosureVerifyS3FailureReleasesConnection1110=== NAME TestOrphanedObjectsGCStressTest1111 orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1112 orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1113--- PASS: TestReadRedirectNar (2.50s)1114=== CONT TestGCSweepDeliversEachKeyOnce1115--- PASS: TestReadRedirectKeepsNarinfoProxied (2.86s)1116=== CONT TestTombstonedObjectOfferedWithoutWaiting1117--- PASS: TestReadProxyDisabled (2.71s)1118=== CONT TestGCSweepSkipsPendingObjects1119=== NAME TestNARDeduplicationMetadataUploadBug1120 metadata_upload_test.go:48: First store path: /nix/var/nix/builds/nix-38141-2789532961/TestNARDeduplicationMetadataUploadBug2575328836/001/store/zjwv04yy7815irfrbwa4f86a3frmyxdw-file1.txt11212026/10/06 11:18:43 INFO Received push request method=POST path=/api/pushes11222026/10/06 11:18:43 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)11232026/10/06 11:18:43 INFO Uploading zjwv04yy7815irfrbwa4f86a3frmyxdw-file1.txt (160B)11242026/10/06 11:18:43 WARN Failed to register uploaded object key=nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst error="server returned 404: 404 page not found\n"11252026/10/06 11:18:43 WARN Failed to register uploaded object key=zjwv04yy7815irfrbwa4f86a3frmyxdw.ls error="server returned 404: 404 page not found\n"11262026/10/06 11:18:43 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign11272026/10/06 11:18:43 INFO Signed narinfos id=1 count=111282026/10/06 11:18:43 INFO Uploading 1 narinfos11292026/10/06 11:18:43 INFO Received complete push request method=POST path=/api/pushes/1/complete11302026/10/06 11:18:43 WARN Failed to register uploaded object key=zjwv04yy7815irfrbwa4f86a3frmyxdw.narinfo error="server returned 404: 404 page not found\n"11312026/10/06 11:18:43 INFO Upload complete. (115ms)1132 metadata_upload_test.go:54: Retrieved narinfo from S3:1133 StorePath: /nix/var/nix/builds/nix-38141-2789532961/TestNARDeduplicationMetadataUploadBug2575328836/001/store/zjwv04yy7815irfrbwa4f86a3frmyxdw-file1.txt1134 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1135 Compression: zstd1136 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1137 NarSize: 1601138 References: 1139 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1140 metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1141 metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1142 {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1143 metadata_upload_test.go:64: Second store path (same content): /nix/var/nix/builds/nix-38141-2789532961/TestNARDeduplicationMetadataUploadBug2575328836/001/store/yc3gv3178gzibwja8b8y24fsh3v3dpbr-file2.txt1144--- PASS: TestReadProxyConditionalGet (2.27s)1145=== CONT TestDeduplicatedObjectsRecordedAsPending11462026/10/06 11:18:43 INFO Received push request method=POST path=/api/pushes11472026/10/06 11:18:43 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)11482026/10/06 11:18:43 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign11492026/10/06 11:18:43 INFO Signed narinfos id=2 count=111502026/10/06 11:18:43 INFO Uploading 1 narinfos11512026/10/06 11:18:43 WARN Failed to register uploaded object key=yc3gv3178gzibwja8b8y24fsh3v3dpbr.ls error="server returned 404: 404 page not found\n"11522026/10/06 11:18:43 INFO Received complete push request method=POST path=/api/pushes/2/complete11532026/10/06 11:18:43 WARN Failed to register uploaded object key=yc3gv3178gzibwja8b8y24fsh3v3dpbr.narinfo error="server returned 404: 404 page not found\n"11542026/10/06 11:18:43 INFO Upload complete. (92ms)1155=== NAME TestNARDeduplicationMetadataUploadBug1156 metadata_upload_test.go:76: Retrieved narinfo from S3:1157 StorePath: /nix/var/nix/builds/nix-38141-2789532961/TestNARDeduplicationMetadataUploadBug2575328836/001/store/yc3gv3178gzibwja8b8y24fsh3v3dpbr-file2.txt1158 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1159 Compression: zstd1160 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1161 NarSize: 1601162 References: 1163 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1164 metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1165 metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1166 {"version":1,"root":{"type":"regular","size":44}}1167--- PASS: TestNARDeduplicationMetadataUploadBug (3.28s)1168=== CONT TestPushDedupSurvivesConcurrentGC1169--- PASS: TestReadProxyHead (2.09s)1170=== CONT TestGCMetrics11712026/10/06 11:18:44 INFO Received uploads request method=POST path=/api/pending_closures1172=== NAME TestOrphanedObjectsGCStressTest1173 orphaned_objects_gc_test.go:509: Stress test completed successfully:1174 orphaned_objects_gc_test.go:510: - Active objects preserved: 201175 orphaned_objects_gc_test.go:511: - Objects deleted: 2101176 orphaned_objects_gc_test.go:512: - Total GC'd: 2101177--- PASS: TestOrphanedObjectsGCStressTest (11.60s)1178=== CONT TestGCBugBareHashReferences11792026/10/06 11:18:44 INFO Received uploads request method=POST path=/api/pending_closures11802026/10/06 11:18:44 INFO Received uploads request method=POST path=/api/pending_closures11812026/10/06 11:18:44 INFO Uploading 2 paths to 127.0.0.1 (1 already cached)11822026/10/06 11:18:44 INFO Uploading inmf9k2hwsd5a653qcznpbj2n48kps8p-b (248B)11832026/10/06 11:18:44 INFO Uploading 195r29kcxla24g48qgvfq8kais6mww30-shared-dep (136B)11842026/10/06 11:18:44 WARN Failed to register uploaded object key=nar/172824h7kajikmgglpb40b1r3zlyx3jqy2f9wmpj7xry3qs0bsh7.nar.zst error="server returned 404: 404 page not found\n"11852026/10/06 11:18:44 WARN Failed to register uploaded object key=gq8xihd76zajvqifpw14axb7a13wmgss.ls error="server returned 404: 404 page not found\n"11862026/10/06 11:18:44 WARN Failed to register uploaded object key=195r29kcxla24g48qgvfq8kais6mww30.ls error="server returned 404: 404 page not found\n"11872026/10/06 11:18:44 WARN Failed to register uploaded object key=inmf9k2hwsd5a653qcznpbj2n48kps8p.ls error="server returned 404: 404 page not found\n"11882026/10/06 11:18:44 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign11892026/10/06 11:18:44 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"11902026/10/06 11:18:44 INFO Signed narinfos id=1 count=211912026/10/06 11:18:44 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign11922026/10/06 11:18:44 INFO Signed narinfos id=2 count=211932026/10/06 11:18:44 INFO Uploading 4 narinfos11942026/10/06 11:18:44 WARN Failed to register uploaded object key=195r29kcxla24g48qgvfq8kais6mww30.narinfo error="server returned 404: 404 page not found\n"11952026/10/06 11:18:44 WARN Failed to register uploaded object key=gq8xihd76zajvqifpw14axb7a13wmgss.narinfo error="server returned 404: 404 page not found\n"11962026/10/06 11:18:44 WARN Failed to register uploaded object key=inmf9k2hwsd5a653qcznpbj2n48kps8p.narinfo error="server returned 404: 404 page not found\n"1197--- PASS: TestCreatePendingClosureVerifyS3FailureReleasesConnection (2.80s)1198=== CONT TestGCAdvisoryLockBlocksConcurrentRun11992026/10/06 11:18:44 INFO Aborted multipart uploads count=0 kept=012002026/10/06 11:18:44 WARN Force mode enabled - objects will be deleted immediately without grace period12012026/10/06 11:18:44 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete12022026/10/06 11:18:44 WARN Failed to register uploaded object key=195r29kcxla24g48qgvfq8kais6mww30.narinfo error="server returned 404: 404 page not found\n"12032026/10/06 11:18:44 INFO Completed upload id=112042026/10/06 11:18:44 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete12052026/10/06 11:18:44 INFO Completed upload id=212062026/10/06 11:18:44 INFO Upload complete. (151ms)1207=== NAME TestClientFallsBackToClosures1208 client_pushes_test.go:112: Retrieved narinfo from S3:1209 StorePath: /nix/var/nix/builds/nix-38141-2789532961/TestClientFallsBackToClosures656590772/001/store/195r29kcxla24g48qgvfq8kais6mww30-shared-dep1210 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1211 Compression: zstd1212 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821213 NarSize: 1361214 References: 1215 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1216 client_pushes_test.go:112: Retrieved narinfo from S3:1217 StorePath: /nix/var/nix/builds/nix-38141-2789532961/TestClientFallsBackToClosures656590772/001/store/gq8xihd76zajvqifpw14axb7a13wmgss-a1218 URL: nar/172824h7kajikmgglpb40b1r3zlyx3jqy2f9wmpj7xry3qs0bsh7.nar.zst1219 Compression: zstd1220 NarHash: sha256:172824h7kajikmgglpb40b1r3zlyx3jqy2f9wmpj7xry3qs0bsh71221 NarSize: 2481222 References: /nix/var/nix/builds/nix-38141-2789532961/TestClientFallsBackToClosures656590772/001/store/195r29kcxla24g48qgvfq8kais6mww30-shared-dep1223 CA: text:sha256:0p4vga210mdmq43sxvswahlx5yqpl3b6vwqdq7fdfa81smcf1lj91224 client_pushes_test.go:112: Retrieved narinfo from S3:1225 StorePath: /nix/var/nix/builds/nix-38141-2789532961/TestClientFallsBackToClosures656590772/001/store/inmf9k2hwsd5a653qcznpbj2n48kps8p-b1226 URL: nar/172824h7kajikmgglpb40b1r3zlyx3jqy2f9wmpj7xry3qs0bsh7.nar.zst1227 Compression: zstd1228 NarHash: sha256:172824h7kajikmgglpb40b1r3zlyx3jqy2f9wmpj7xry3qs0bsh71229 NarSize: 2481230 References: /nix/var/nix/builds/nix-38141-2789532961/TestClientFallsBackToClosures656590772/001/store/195r29kcxla24g48qgvfq8kais6mww30-shared-dep1231 CA: text:sha256:0p4vga210mdmq43sxvswahlx5yqpl3b6vwqdq7fdfa81smcf1lj912322026/10/06 11:18:44 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=3 objects-deleted-after-grace-period=3 objects-failed-to-delete=012332026/10/06 11:18:44 INFO Vacuumed table table=pending_closures12342026/10/06 11:18:44 INFO Vacuumed table table=pending_objects12352026/10/06 11:18:44 INFO Vacuumed table table=multipart_uploads12362026/10/06 11:18:44 INFO Vacuumed table table=closures12372026/10/06 11:18:44 INFO Vacuumed table table=objects1238--- PASS: TestGCSweepDeliversEachKeyOnce (2.37s)1239=== CONT TestLeadEndsOnShutdown1240--- PASS: TestClientFallsBackToClosures (2.95s)1241=== CONT TestLeadIncumbentAnnouncesAtOnce12422026/10/06 11:18:44 INFO Received uploads request method=POST path=/api/pending_closures1243--- PASS: TestTombstonedObjectOfferedWithoutWaiting (2.24s)1244=== CONT TestLeadElectsOneAndHandsOver12452026/10/06 11:18:44 INFO Received uploads request method=POST path=/api/pending_closures12462026/10/06 11:18:44 INFO Aborted multipart uploads count=0 kept=012472026/10/06 11:18:44 WARN Force mode enabled - objects will be deleted immediately without grace period12482026/10/06 11:18:44 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=012492026/10/06 11:18:44 INFO Vacuumed table table=pending_closures12502026/10/06 11:18:44 INFO Vacuumed table table=pending_objects12512026/10/06 11:18:44 INFO Vacuumed table table=multipart_uploads12522026/10/06 11:18:44 INFO Vacuumed table table=closures12532026/10/06 11:18:44 INFO Vacuumed table table=objects1254--- PASS: TestGCSweepSkipsPendingObjects (2.21s)1255=== CONT TestConnectWaitsForAPeerMigration12562026/10/06 11:18:44 INFO Received uploads request method=POST path=/api/pending_closures1257--- PASS: TestDeduplicatedObjectsRecordedAsPending (1.61s)1258=== CONT TestResolveDBConnectionString1259=== RUN TestResolveDBConnectionString/flag_wins1260=== PAUSE TestResolveDBConnectionString/flag_wins1261=== RUN TestResolveDBConnectionString/file_when_flag_empty1262=== PAUSE TestResolveDBConnectionString/file_when_flag_empty1263=== RUN TestResolveDBConnectionString/missing_file_is_an_error1264=== PAUSE TestResolveDBConnectionString/missing_file_is_an_error1265=== RUN TestResolveDBConnectionString/PGHOST_allows_empty1266=== PAUSE TestResolveDBConnectionString/PGHOST_allows_empty1267=== RUN TestResolveDBConnectionString/nothing_configured1268=== PAUSE TestResolveDBConnectionString/nothing_configured1269=== CONT TestConcurrentCommitsSharingObjectsDoNotDeadlock12702026/10/06 11:18:45 INFO Received uploads request method=POST path=/api/pending_closures12712026/10/06 11:18:45 INFO Aborted multipart uploads count=0 kept=012722026/10/06 11:18:45 INFO Aborted multipart uploads count=0 kept=012732026/10/06 11:18:45 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=012742026/10/06 11:18:45 WARN Force mode enabled - objects will be deleted immediately without grace period12752026/10/06 11:18:45 INFO Vacuumed table table=pending_closures12762026/10/06 11:18:45 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=012772026/10/06 11:18:45 INFO Vacuumed table table=pending_closures12782026/10/06 11:18:45 INFO Vacuumed table table=pending_objects12792026/10/06 11:18:45 INFO Vacuumed table table=pending_objects12802026/10/06 11:18:45 INFO Vacuumed table table=multipart_uploads12812026/10/06 11:18:45 INFO Vacuumed table table=closures12822026/10/06 11:18:45 INFO Vacuumed table table=multipart_uploads12832026/10/06 11:18:45 INFO Vacuumed table table=objects1284--- PASS: TestGCMetrics (1.84s)1285=== CONT TestReadProxyInvalidPath12862026/10/06 11:18:45 INFO Vacuumed table table=closures12872026/10/06 11:18:45 INFO Vacuumed table table=objects12882026/10/06 11:18:45 INFO Aborted multipart uploads count=0 kept=012892026/10/06 11:18:45 WARN Force mode enabled - objects will be deleted immediately without grace period12902026/10/06 11:18:45 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=012912026/10/06 11:18:45 INFO Vacuumed table table=pending_closures12922026/10/06 11:18:45 INFO Vacuumed table table=pending_objects12932026/10/06 11:18:45 INFO Vacuumed table table=multipart_uploads12942026/10/06 11:18:45 INFO Vacuumed table table=closures12952026/10/06 11:18:45 INFO Vacuumed table table=objects1296--- PASS: TestPushDedupSurvivesConcurrentGC (1.98s)1297=== CONT TestGCTaskStore_GetEmpty1298--- PASS: TestGCTaskStore_GetEmpty (0.00s)1299=== CONT TestGenerateLandingPage1300--- PASS: TestGenerateLandingPage (0.00s)1301=== CONT TestService_readinessHandler13022026/10/06 11:18:45 ERROR failed to check GC advisory lock error="query pg_locks: failed to connect to `user=_nixbld1 database=niks3`: /nonexistent/.s.PGSQL.5432 (/nonexistent): dial error: dial unix /nonexistent/.s.PGSQL.5432: connect: no such file or directory"1303--- PASS: TestGCAdvisoryLockBlocksConcurrentRun (1.57s)1304=== CONT TestService_healthCheckHandler1305--- PASS: TestGCBugBareHashReferences (1.75s)1306=== CONT TestGracefulShutdownDrainsInflight13072026/10/06 11:18:45 INFO Starting HTTP server address=127.0.0.1:5491513082026/10/06 11:18:45 INFO Shutdown signal received, draining in-flight requests timeout=10s1309--- PASS: TestGracefulShutdownDrainsInflight (0.07s)1310=== CONT TestGCTaskStore_Fail1311--- PASS: TestGCTaskStore_Fail (0.00s)1312=== CONT TestGCTaskStore_PhaseUpdates1313--- PASS: TestGCTaskStore_PhaseUpdates (0.00s)1314=== CONT TestGCTaskStore_CompletedAllowsNewTask1315--- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)1316=== CONT TestGCTaskStore_GetReturnsLatest1317--- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)1318=== CONT TestGCEndsOnShutdown1319=== RUN TestGCEndsOnShutdown/before_the_run1320=== PAUSE TestGCEndsOnShutdown/before_the_run1321=== RUN TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete1322=== PAUSE TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete1323=== RUN TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark1324=== PAUSE TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark1325=== CONT TestGCTaskStore_ConflictDifferentParams1326--- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)1327=== CONT TestGCTaskStore_DeduplicateSameParams1328--- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)1329=== CONT TestGCTaskStore_StartNew1330--- PASS: TestGCTaskStore_StartNew (0.00s)1331=== CONT TestClientCADerivations13322026/10/06 11:18:46 INFO lead: acquired remote=192.0.2.1:123413332026/10/06 11:18:46 INFO lead: released remote=192.0.2.1:12341334--- PASS: TestLeadIncumbentAnnouncesAtOnce (1.62s)1335=== CONT TestService_RequireScope_OIDC13362026/10/06 11:18:46 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:54919/oidc13372026/10/06 11:18:46 INFO lead: acquired remote=192.0.2.1:123413382026/10/06 11:18:46 INFO lead: acquired remote=192.0.2.1:123413392026/10/06 11:18:46 WARN readiness check failed error="closed pool"1340--- PASS: TestService_readinessHandler (1.31s)1341=== CONT TestClientPushesUseOnePush1342--- PASS: TestReadProxyInvalidPath (1.56s)1343=== CONT TestCacheStatsHandler1344--- PASS: TestService_healthCheckHandler (1.23s)1345=== CONT TestPinProtectsFromGC1346--- PASS: TestReadProxyOutlastsServerWriteTimeout (6.54s)1347=== CONT TestCacheConfigHandler1348=== RUN TestCacheConfigHandler/full_config,_no_issuer1349=== PAUSE TestCacheConfigHandler/full_config,_no_issuer1350=== RUN TestCacheConfigHandler/no_cache_url_configured1351=== PAUSE TestCacheConfigHandler/no_cache_url_configured1352=== RUN TestCacheConfigHandler/no_signing_keys1353=== PAUSE TestCacheConfigHandler/no_signing_keys1354=== RUN TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1355=== PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1356=== CONT TestClientSharedPathCommittedMidPush13572026/10/06 11:18:47 INFO lead: released remote=192.0.2.1:12341358--- PASS: TestLeadEndsOnShutdown (2.92s)1359=== CONT TestService_ReadScope_PublicByDefault1360--- PASS: TestConcurrentCommitsSharingObjectsDoNotDeadlock (2.44s)1361=== CONT TestPushWaitsForSweepHoldingRow1362=== RUN TestService_RequireScope_OIDC/builder_may_write1363=== PAUSE TestService_RequireScope_OIDC/builder_may_write1364=== RUN TestService_RequireScope_OIDC/builder_may_not_admin1365=== PAUSE TestService_RequireScope_OIDC/builder_may_not_admin1366=== RUN TestService_RequireScope_OIDC/ops_may_admin1367=== PAUSE TestService_RequireScope_OIDC/ops_may_admin1368=== RUN TestService_RequireScope_OIDC/ops_may_not_write1369=== PAUSE TestService_RequireScope_OIDC/ops_may_not_write1370=== RUN TestService_RequireScope_OIDC/reader_may_not_write1371=== PAUSE TestService_RequireScope_OIDC/reader_may_not_write1372=== RUN TestService_RequireScope_OIDC/static_token_may_admin1373=== PAUSE TestService_RequireScope_OIDC/static_token_may_admin1374=== RUN TestService_RequireScope_OIDC/static_token_may_write1375=== PAUSE TestService_RequireScope_OIDC/static_token_may_write1376=== RUN TestService_RequireScope_OIDC/reader_may_read1377=== PAUSE TestService_RequireScope_OIDC/reader_may_read1378=== RUN TestService_RequireScope_OIDC/writer_implies_read1379=== PAUSE TestService_RequireScope_OIDC/writer_implies_read1380=== RUN TestService_RequireScope_OIDC/anonymous_may_not_read1381=== PAUSE TestService_RequireScope_OIDC/anonymous_may_not_read1382=== RUN TestService_RequireScope_OIDC/reader_may_read_with_netrc1383=== PAUSE TestService_RequireScope_OIDC/reader_may_read_with_netrc1384=== RUN TestService_RequireScope_OIDC/static_token_may_admin_with_netrc1385=== PAUSE TestService_RequireScope_OIDC/static_token_may_admin_with_netrc1386=== RUN TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read1387=== PAUSE TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read1388=== RUN TestService_RequireScope_OIDC/empty_netrc_password_may_not_read1389=== PAUSE TestService_RequireScope_OIDC/empty_netrc_password_may_not_read1390=== CONT TestCommitRacingPendingCleanupKeepsObjects13912026/10/06 11:18:47 INFO lead: released remote=192.0.2.1:123413922026/10/06 11:18:47 INFO lead: acquired remote=192.0.2.1:12341393=== NAME TestClientCADerivations1394 client_ca_test.go:136: Built CA derivation: /nix/var/nix/builds/nix-38141-2789532961/TestClientCADerivations3188310243/001/store/f3nv1li9xcn6mqi0k1973kn9xk922x0k-ca-test1395 client_ca_test.go:139: Found 1 dependencies (including self)13962026/10/06 11:18:47 INFO Received push request method=POST path=/api/pushes13972026/10/06 11:18:47 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)13982026/10/06 11:18:47 INFO Uploading f3nv1li9xcn6mqi0k1973kn9xk922x0k-ca-test (144B)13992026/10/06 11:18:47 WARN Failed to register uploaded object key=nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst error="server returned 404: 404 page not found\n"14002026/10/06 11:18:47 WARN Failed to register uploaded object key=f3nv1li9xcn6mqi0k1973kn9xk922x0k.ls error="server returned 404: 404 page not found\n"14012026/10/06 11:18:47 WARN Failed to register uploaded object key=log/i98fcmpaqc89bvz18q6fjq7spmj4gxmm-ca-test.drv error="server returned 404: 404 page not found\n"14022026/10/06 11:18:47 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign14032026/10/06 11:18:47 INFO Signed narinfos id=1 count=114042026/10/06 11:18:47 INFO Uploading 1 narinfos14052026/10/06 11:18:47 INFO Received complete push request method=POST path=/api/pushes/1/complete14062026/10/06 11:18:47 WARN Failed to register uploaded object key=f3nv1li9xcn6mqi0k1973kn9xk922x0k.narinfo error="server returned 404: 404 page not found\n"14072026/10/06 11:18:47 INFO Upload complete. (164ms)1408 client_ca_test.go:180: Narinfo contains CA field: StorePath: /nix/var/nix/builds/nix-38141-2789532961/TestClientCADerivations3188310243/001/store/f3nv1li9xcn6mqi0k1973kn9xk922x0k-ca-test1409 URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1410 Compression: zstd1411 NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1412 NarSize: 1441413 References: 1414 Deriver: /nix/var/nix/builds/nix-38141-2789532961/TestClientCADerivations3188310243/001/store/i98fcmpaqc89bvz18q6fjq7spmj4gxmm-ca-test.drv1415 CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1416 client_ca_test.go:185: Checking for realisation files in S3...1417 client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1418 client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache1419 client_ca_test.go:258: nix copy output: error: binary cache 's3://bucket67?endpoint=http://localhost:54681®ion=eu-west-1' is for Nix stores with prefix '/nix/store', not '/nix/var/nix/builds/nix-38141-2789532961/TestClientCADerivations3188310243/001/store'1420 client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11421--- PASS: TestClientCADerivations (2.00s)1422=== CONT TestClientWithDependencies14232026/10/06 11:18:48 INFO Received push request method=POST path=/api/pushes14242026/10/06 11:18:48 INFO Uploading 2 paths to 127.0.0.1 (1 already cached)14252026/10/06 11:18:48 INFO Uploading v5nk93pql7m86rvv9l0fkrmirsqr7nhq-a (248B)14262026/10/06 11:18:48 INFO Uploading ynblm6v0y8h1ac4d6j3qlsl8v53rng11-shared-dep (136B)1427--- PASS: TestCacheStatsHandler (1.39s)1428=== CONT TestClientErrorHandling1429=== RUN TestClientErrorHandling/InvalidStorePath1430=== PAUSE TestClientErrorHandling/InvalidStorePath1431=== RUN TestClientErrorHandling/InvalidAuthToken1432=== PAUSE TestClientErrorHandling/InvalidAuthToken1433=== RUN TestClientErrorHandling/ServerNotAvailable1434=== PAUSE TestClientErrorHandling/ServerNotAvailable1435=== CONT TestClientIntegration14362026/10/06 11:18:48 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"14372026/10/06 11:18:48 WARN Failed to register uploaded object key=ynblm6v0y8h1ac4d6j3qlsl8v53rng11.ls error="server returned 404: 404 page not found\n"14382026/10/06 11:18:48 WARN Failed to register uploaded object key=hbgfdj80zwk8agsz738cldags5rpqfw1.ls error="server returned 404: 404 page not found\n"14392026/10/06 11:18:48 WARN Failed to register uploaded object key=nar/0p9nzla08xs41cls7b8405vs4ll02v2l3k846slkxh6pap0d245a.nar.zst error="server returned 404: 404 page not found\n"14402026/10/06 11:18:48 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign14412026/10/06 11:18:48 WARN Failed to register uploaded object key=v5nk93pql7m86rvv9l0fkrmirsqr7nhq.ls error="server returned 404: 404 page not found\n"14422026/10/06 11:18:48 INFO Signed narinfos id=1 count=314432026/10/06 11:18:48 INFO Uploading 3 narinfos14442026/10/06 11:18:48 WARN Failed to register uploaded object key=hbgfdj80zwk8agsz738cldags5rpqfw1.narinfo error="server returned 404: 404 page not found\n"14452026/10/06 11:18:48 WARN Failed to register uploaded object key=v5nk93pql7m86rvv9l0fkrmirsqr7nhq.narinfo error="server returned 404: 404 page not found\n"14462026/10/06 11:18:48 INFO Received complete push request method=POST path=/api/pushes/1/complete14472026/10/06 11:18:48 WARN Failed to register uploaded object key=ynblm6v0y8h1ac4d6j3qlsl8v53rng11.narinfo error="server returned 404: 404 page not found\n"14482026/10/06 11:18:48 INFO Upload complete. (194ms)1449=== NAME TestClientPushesUseOnePush1450 client_pushes_test.go:97: Retrieved narinfo from S3:1451 StorePath: /nix/var/nix/builds/nix-38141-2789532961/TestClientPushesUseOnePush504512267/001/store/ynblm6v0y8h1ac4d6j3qlsl8v53rng11-shared-dep1452 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1453 Compression: zstd1454 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821455 NarSize: 1361456 References: 1457 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1458 client_pushes_test.go:97: Retrieved narinfo from S3:1459 StorePath: /nix/var/nix/builds/nix-38141-2789532961/TestClientPushesUseOnePush504512267/001/store/v5nk93pql7m86rvv9l0fkrmirsqr7nhq-a1460 URL: nar/0p9nzla08xs41cls7b8405vs4ll02v2l3k846slkxh6pap0d245a.nar.zst1461 Compression: zstd1462 NarHash: sha256:0p9nzla08xs41cls7b8405vs4ll02v2l3k846slkxh6pap0d245a1463 NarSize: 2481464 References: /nix/var/nix/builds/nix-38141-2789532961/TestClientPushesUseOnePush504512267/001/store/ynblm6v0y8h1ac4d6j3qlsl8v53rng11-shared-dep1465 CA: text:sha256:06jj0qad141xjxmv3pzlkz1hmz7y2j4j1gamf73fdnla7wwj86xj1466 client_pushes_test.go:97: Retrieved narinfo from S3:1467 StorePath: /nix/var/nix/builds/nix-38141-2789532961/TestClientPushesUseOnePush504512267/001/store/hbgfdj80zwk8agsz738cldags5rpqfw1-b1468 URL: nar/0p9nzla08xs41cls7b8405vs4ll02v2l3k846slkxh6pap0d245a.nar.zst1469 Compression: zstd1470 NarHash: sha256:0p9nzla08xs41cls7b8405vs4ll02v2l3k846slkxh6pap0d245a1471 NarSize: 2481472 References: /nix/var/nix/builds/nix-38141-2789532961/TestClientPushesUseOnePush504512267/001/store/ynblm6v0y8h1ac4d6j3qlsl8v53rng11-shared-dep1473 CA: text:sha256:06jj0qad141xjxmv3pzlkz1hmz7y2j4j1gamf73fdnla7wwj86xj1474--- PASS: TestClientPushesUseOnePush (1.77s)1475=== CONT TestClientMultipleUploads14762026/10/06 11:18:48 INFO lead: released remote=192.0.2.1:12341477--- PASS: TestLeadElectsOneAndHandsOver (4.14s)1478=== CONT TestService_ReadAuthMiddleware1479=== NAME TestPinProtectsFromGC1480 client_integration_test.go:731: Pinned store path: /nix/var/nix/builds/nix-38141-2789532961/TestPinProtectsFromGC131517765/001/store/knbj0gm1x1bdhz29ly66s96gcrr8f26s-pinned-file.txt1481 client_integration_test.go:732: Unpinned store path: /nix/var/nix/builds/nix-38141-2789532961/TestPinProtectsFromGC131517765/001/store/2nkbwdbzyvi9vhz899f250b1k7lrmcfc-unpinned-file.txt14822026/10/06 11:18:48 INFO Received push request method=POST path=/api/pushes14832026/10/06 11:18:49 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14842026/10/06 11:18:49 INFO Uploading knbj0gm1x1bdhz29ly66s96gcrr8f26s-pinned-file.txt (128B)14852026/10/06 11:18:49 WARN Failed to register uploaded object key=nar/0qmhsg42pqsz7x6hh02j8gznnac3yi3qpjhrqbwvz88i194jbb5s.nar.zst error="server returned 404: 404 page not found\n"14862026/10/06 11:18:49 WARN Failed to register uploaded object key=knbj0gm1x1bdhz29ly66s96gcrr8f26s.ls error="server returned 404: 404 page not found\n"14872026/10/06 11:18:49 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign14882026/10/06 11:18:49 INFO Signed narinfos id=1 count=114892026/10/06 11:18:49 INFO Uploading 1 narinfos14902026/10/06 11:18:49 INFO Received complete push request method=POST path=/api/pushes/1/complete14912026/10/06 11:18:49 WARN Failed to register uploaded object key=knbj0gm1x1bdhz29ly66s96gcrr8f26s.narinfo error="server returned 404: 404 page not found\n"14922026/10/06 11:18:49 INFO Upload complete. (187ms)14932026/10/06 11:18:49 INFO Aborted multipart uploads count=0 kept=014942026/10/06 11:18:49 WARN Force mode enabled - objects will be deleted immediately without grace period14952026/10/06 11:18:49 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=014962026/10/06 11:18:49 INFO Vacuumed table table=pending_closures14972026/10/06 11:18:49 INFO Vacuumed table table=pending_objects14982026/10/06 11:18:49 INFO Vacuumed table table=multipart_uploads14992026/10/06 11:18:49 INFO Received push request method=POST path=/api/pushes15002026/10/06 11:18:49 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15012026/10/06 11:18:49 INFO Uploading 2nkbwdbzyvi9vhz899f250b1k7lrmcfc-unpinned-file.txt (128B)15022026/10/06 11:18:49 INFO Vacuumed table table=closures15032026/10/06 11:18:49 WARN Failed to register uploaded object key=2nkbwdbzyvi9vhz899f250b1k7lrmcfc.ls error="server returned 404: 404 page not found\n"15042026/10/06 11:18:49 INFO Vacuumed table table=objects15052026/10/06 11:18:49 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign15062026/10/06 11:18:49 INFO Signed narinfos id=2 count=115072026/10/06 11:18:49 WARN Failed to register uploaded object key=nar/0gh0wdnr6gmk4r5sfzzh12isnl14rgmkrwy1ws41hpsrlndclg5h.nar.zst error="server returned 404: 404 page not found\n"15082026/10/06 11:18:49 INFO Uploading 1 narinfos1509--- PASS: TestCommitRacingPendingCleanupKeepsObjects (1.67s)1510=== CONT TestService_AuthMiddleware_OIDC15112026/10/06 11:18:49 INFO Received complete push request method=POST path=/api/pushes/2/complete15122026/10/06 11:18:49 WARN Failed to register uploaded object key=2nkbwdbzyvi9vhz899f250b1k7lrmcfc.narinfo error="server returned 404: 404 page not found\n"15132026/10/06 11:18:49 INFO Upload complete. (128ms)15142026/10/06 11:18:49 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:54989/oidc15152026/10/06 11:18:49 INFO Starting cleanup of old closures method=DELETE path=/api/closures15162026/10/06 11:18:49 INFO Garbage collection started15172026/10/06 11:18:49 INFO Aborted multipart uploads count=0 kept=015182026/10/06 11:18:49 WARN Force mode enabled - objects will be deleted immediately without grace period15192026/10/06 11:18:49 INFO Received uploads request method=POST path=/api/pending_closures15202026/10/06 11:18:49 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=015212026/10/06 11:18:49 INFO Vacuumed table table=pending_closures15222026/10/06 11:18:49 INFO Vacuumed table table=pending_objects15232026/10/06 11:18:49 INFO Vacuumed table table=multipart_uploads15242026/10/06 11:18:49 INFO Vacuumed table table=closures15252026/10/06 11:18:49 INFO Vacuumed table table=objects1526--- PASS: TestService_ReadScope_PublicByDefault (2.24s)1527=== CONT TestSweepRechecksPendingAfterLocking1528--- PASS: TestPushWaitsForSweepHoldingRow (2.41s)1529=== CONT TestGetExistingObjectsLocksRows15302026/10/06 11:18:50 INFO Received push request method=POST path=/api/pushes15312026/10/06 11:18:50 INFO Uploading 2 paths to 127.0.0.1 (0 already cached)15322026/10/06 11:18:50 INFO Uploading mi21gnxrwghpl6d2i17zh7wchpx15z2m-shared-dep (136B)15332026/10/06 11:18:50 INFO Uploading 643mynm3ikjcpxgsf6ag053xn9a64kzk-top (256B)15342026/10/06 11:18:50 WARN Failed to register uploaded object key=nar/0450lvansgd7b465lzjwd7jh1a8aqpq6hyla6814vh7kki1h85jk.nar.zst error="server returned 404: 404 page not found\n"15352026/10/06 11:18:50 WARN Failed to register uploaded object key=643mynm3ikjcpxgsf6ag053xn9a64kzk.ls error="server returned 404: 404 page not found\n"15362026/10/06 11:18:50 WARN Failed to register uploaded object key=mi21gnxrwghpl6d2i17zh7wchpx15z2m.ls error="server returned 404: 404 page not found\n"15372026/10/06 11:18:50 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign15382026/10/06 11:18:50 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"15392026/10/06 11:18:50 INFO Signed narinfos id=1 count=215402026/10/06 11:18:50 INFO Uploading 2 narinfos15412026/10/06 11:18:50 WARN Failed to register uploaded object key=643mynm3ikjcpxgsf6ag053xn9a64kzk.narinfo error="server returned 404: 404 page not found\n"15422026/10/06 11:18:50 INFO Received complete push request method=POST path=/api/pushes/1/complete15432026/10/06 11:18:50 WARN Failed to register uploaded object key=mi21gnxrwghpl6d2i17zh7wchpx15z2m.narinfo error="server returned 404: 404 page not found\n"15442026/10/06 11:18:50 INFO Upload complete. (159ms)1545=== NAME TestClientSharedPathCommittedMidPush1546 client_integration_test.go:680: Retrieved narinfo from S3:1547 StorePath: /nix/var/nix/builds/nix-38141-2789532961/TestClientSharedPathCommittedMidPush202359219/001/store/mi21gnxrwghpl6d2i17zh7wchpx15z2m-shared-dep1548 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1549 Compression: zstd1550 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821551 NarSize: 1361552 References: 1553 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1554 client_integration_test.go:680: Retrieved narinfo from S3:1555 StorePath: /nix/var/nix/builds/nix-38141-2789532961/TestClientSharedPathCommittedMidPush202359219/001/store/643mynm3ikjcpxgsf6ag053xn9a64kzk-top1556 URL: nar/0450lvansgd7b465lzjwd7jh1a8aqpq6hyla6814vh7kki1h85jk.nar.zst1557 Compression: zstd1558 NarHash: sha256:0450lvansgd7b465lzjwd7jh1a8aqpq6hyla6814vh7kki1h85jk1559 NarSize: 2561560 References: /nix/var/nix/builds/nix-38141-2789532961/TestClientSharedPathCommittedMidPush202359219/001/store/mi21gnxrwghpl6d2i17zh7wchpx15z2m-shared-dep1561 CA: text:sha256:19m78lgl44dfdp97r91qq40pfqri5kflqxb0i4xw3hkl2nnfl7wi1562--- PASS: TestClientSharedPathCommittedMidPush (3.04s)1563=== CONT TestService_AuthMiddleware_MTLSBoundSubjects1564=== NAME TestClientWithDependencies1565 client_integration_test.go:613: Built derivation: /nix/var/nix/builds/nix-38141-2789532961/TestClientWithDependencies2750177565/001/store/sl4p5vrqjaqxl9fy6igqg5n753v84wsb-test-script1566 client_integration_test.go:615: Found 1 dependencies (including self)1567=== NAME TestClientIntegration1568 client_integration_test.go:286: Created store path: /nix/var/nix/builds/nix-38141-2789532961/TestClientIntegration1889547826/002/store/d2hs3djkmiiac8sq7fmsd5psg4bhavzw-test-file.txt15692026/10/06 11:18:50 INFO Received push request method=POST path=/api/pushes15702026/10/06 11:18:50 INFO Received push request method=POST path=/api/pushes15712026/10/06 11:18:50 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15722026/10/06 11:18:50 INFO Uploading sl4p5vrqjaqxl9fy6igqg5n753v84wsb-test-script (136B)15732026/10/06 11:18:50 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)15742026/10/06 11:18:50 INFO Uploading d2hs3djkmiiac8sq7fmsd5psg4bhavzw-test-file.txt (152B)15752026/10/06 11:18:50 WARN Failed to register uploaded object key=sl4p5vrqjaqxl9fy6igqg5n753v84wsb.ls error="server returned 404: 404 page not found\n"15762026/10/06 11:18:50 WARN Failed to register uploaded object key=nar/1s7yghixzpinyqm7g59kyn3sav81pmnsdi5v63m6x4r2vacy907j.nar.zst error="server returned 404: 404 page not found\n"15772026/10/06 11:18:50 WARN Failed to register uploaded object key=nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst error="server returned 404: 404 page not found\n"15782026/10/06 11:18:50 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign15792026/10/06 11:18:50 WARN Failed to register uploaded object key=d2hs3djkmiiac8sq7fmsd5psg4bhavzw.ls error="server returned 404: 404 page not found\n"15802026/10/06 11:18:50 INFO Signed narinfos id=1 count=115812026/10/06 11:18:50 INFO Uploading 1 narinfos15822026/10/06 11:18:50 WARN Failed to register uploaded object key=log/q1q6nig1y1pr7p7wbr1iry3i78a3pb9c-test-script.drv error="server returned 404: 404 page not found\n"15832026/10/06 11:18:50 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign15842026/10/06 11:18:50 INFO Signed narinfos id=1 count=115852026/10/06 11:18:50 INFO Uploading 1 narinfos15862026/10/06 11:18:50 INFO Received complete push request method=POST path=/api/pushes/1/complete15872026/10/06 11:18:50 WARN Failed to register uploaded object key=d2hs3djkmiiac8sq7fmsd5psg4bhavzw.narinfo error="server returned 404: 404 page not found\n"15882026/10/06 11:18:50 INFO Upload complete. (168ms)15892026/10/06 11:18:50 INFO Received complete push request method=POST path=/api/pushes/1/complete15902026/10/06 11:18:50 WARN Failed to register uploaded object key=sl4p5vrqjaqxl9fy6igqg5n753v84wsb.narinfo error="server returned 404: 404 page not found\n"15912026/10/06 11:18:50 INFO All 1 paths already cached15922026/10/06 11:18:50 INFO Upload complete. (207ms)1593 client_integration_test.go:312: Retrieved narinfo from S3:1594 StorePath: /nix/var/nix/builds/nix-38141-2789532961/TestClientIntegration1889547826/002/store/d2hs3djkmiiac8sq7fmsd5psg4bhavzw-test-file.txt1595 URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1596 Compression: zstd1597 NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11598 NarSize: 1521599 References: 1600 CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11601=== NAME TestClientWithDependencies1602 client_integration_test.go:617: Skipping nix copy test - isolated store (/nix/var/nix/builds/nix-38141-2789532961/TestClientWithDependencies2750177565/001/store) requires matching store prefix1603=== NAME TestClientIntegration1604 client_integration_test.go:313: Retrieved .ls file from S3 (compressed size: 77 bytes)1605 client_integration_test.go:313: Decompressed .ls content (64 bytes):1606 {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1607 client_integration_test.go:316: Testing garbage collection...16082026/10/06 11:18:50 INFO Starting cleanup of old closures method=DELETE path=/api/closures16092026/10/06 11:18:50 INFO Garbage collection started16102026/10/06 11:18:50 INFO Aborted multipart uploads count=0 kept=016112026/10/06 11:18:50 WARN Force mode enabled - objects will be deleted immediately without grace period1612--- PASS: TestClientWithDependencies (2.92s)1613=== CONT TestService_AuthMiddleware_MTLSProxyHeader1614=== NAME TestClientMultipleUploads1615 client_integration_test.go:358: Created store path 0: /nix/var/nix/builds/nix-38141-2789532961/TestClientMultipleUploads3842535981/001/store/k2rr5wni1y92fb5k9bb1d4zm33y7d8ar-test-file-0.txt16162026/10/06 11:18:50 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=01617--- PASS: TestService_ReadAuthMiddleware (2.28s)1618=== CONT TestUploadHandlersRejectOversizedBody/create_pending_closure16192026/10/06 11:18:51 INFO Received uploads request method=POST path=/16202026/10/06 11:18:51 INFO Vacuumed table table=pending_closures16212026/10/06 11:18:51 INFO Vacuumed table table=pending_objects16222026/10/06 11:18:51 INFO Vacuumed table table=multipart_uploads16232026/10/06 11:18:51 INFO Vacuumed table table=closures16242026/10/06 11:18:51 INFO Vacuumed table table=objects1625=== NAME TestClientMultipleUploads1626 client_integration_test.go:358: Created store path 1: /nix/var/nix/builds/nix-38141-2789532961/TestClientMultipleUploads3842535981/001/store/nssvr0sr23bbi8fp0h6sgzrpjx1aad69-test-file-1.txt1627 client_integration_test.go:358: Created store path 2: /nix/var/nix/builds/nix-38141-2789532961/TestClientMultipleUploads3842535981/001/store/pfdfpy2g2mg3bgpcxrayzj4pbxgl8g7c-test-file-2.txt16282026/10/06 11:18:51 INFO Received push request method=POST path=/api/pushes16292026/10/06 11:18:51 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)16302026/10/06 11:18:51 INFO Uploading k2rr5wni1y92fb5k9bb1d4zm33y7d8ar-test-file-0.txt (160B)16312026/10/06 11:18:51 INFO Uploading pfdfpy2g2mg3bgpcxrayzj4pbxgl8g7c-test-file-2.txt (160B)16322026/10/06 11:18:51 INFO Uploading nssvr0sr23bbi8fp0h6sgzrpjx1aad69-test-file-1.txt (160B)16332026/10/06 11:18:51 WARN Failed to register uploaded object key=nar/0ahq3izw3h0b0c08p3xdfclh758fwxnp85ccvflasb4vnfsv50ri.nar.zst error="server returned 404: 404 page not found\n"16342026/10/06 11:18:51 WARN Failed to register uploaded object key=k2rr5wni1y92fb5k9bb1d4zm33y7d8ar.ls error="server returned 404: 404 page not found\n"16352026/10/06 11:18:51 WARN Failed to register uploaded object key=nar/13p9pqi0ga6fx2apcwls9hjnv7nmf740ggpn5zvfzdsp5vz2bbg4.nar.zst error="server returned 404: 404 page not found\n"16362026/10/06 11:18:51 WARN Failed to register uploaded object key=pfdfpy2g2mg3bgpcxrayzj4pbxgl8g7c.ls error="server returned 404: 404 page not found\n"16372026/10/06 11:18:51 WARN Failed to register uploaded object key=nar/1823g6vx4q5zqvr73m7lkq1wpk40fin7bsc792di430yk7sg4lka.nar.zst error="server returned 404: 404 page not found\n"16382026/10/06 11:18:51 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign16392026/10/06 11:18:51 WARN Failed to register uploaded object key=nssvr0sr23bbi8fp0h6sgzrpjx1aad69.ls error="server returned 404: 404 page not found\n"16402026/10/06 11:18:51 INFO Signed narinfos id=1 count=316412026/10/06 11:18:51 INFO Uploading 3 narinfos1642=== CONT TestUploadHandlersRejectOversizedBody/request_more_parts16432026/10/06 11:18:51 INFO Received request for more parts method=POST path=/16442026/10/06 11:18:51 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=3 objects_failed=016452026/10/06 11:18:51 INFO Received create pin request method=POST path=/api/pins/myapp16462026/10/06 11:18:51 WARN Failed to register uploaded object key=k2rr5wni1y92fb5k9bb1d4zm33y7d8ar.narinfo error="server returned 404: 404 page not found\n"16472026/10/06 11:18:51 INFO Received complete push request method=POST path=/api/pushes/1/complete16482026/10/06 11:18:51 WARN Failed to register uploaded object key=pfdfpy2g2mg3bgpcxrayzj4pbxgl8g7c.narinfo error="server returned 404: 404 page not found\n"16492026/10/06 11:18:51 WARN Failed to register uploaded object key=nssvr0sr23bbi8fp0h6sgzrpjx1aad69.narinfo error="server returned 404: 404 page not found\n"16502026/10/06 11:18:51 INFO Created/updated pin name=myapp store_path=/nix/var/nix/builds/nix-38141-2789532961/TestPinProtectsFromGC131517765/001/store/knbj0gm1x1bdhz29ly66s96gcrr8f26s-pinned-file.txt narinfo_key=knbj0gm1x1bdhz29ly66s96gcrr8f26s.narinfo16512026/10/06 11:18:51 INFO Starting cleanup of old closures method=DELETE path=/api/closures16522026/10/06 11:18:51 INFO Garbage collection started16532026/10/06 11:18:51 INFO Aborted multipart uploads count=0 kept=016542026/10/06 11:18:51 WARN Force mode enabled - objects will be deleted immediately without grace period16552026/10/06 11:18:51 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=016562026/10/06 11:18:51 INFO Vacuumed table table=pending_closures16572026/10/06 11:18:51 INFO Vacuumed table table=pending_objects16582026/10/06 11:18:51 INFO Vacuumed table table=multipart_uploads16592026/10/06 11:18:51 INFO Vacuumed table table=closures16602026/10/06 11:18:51 INFO Vacuumed table table=objects1661=== CONT TestUploadHandlersRejectOversizedBody/complete_multipart16622026/10/06 11:18:51 INFO Received complete multipart upload request method=POST path=/1663--- PASS: TestUploadHandlersRejectOversizedBody (0.04s)1664 --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.27s)1665 --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.02s)1666 --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.02s)1667=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info16682026/10/06 11:18:51 INFO Received uploads request method=POST path=/1669=== CONT TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key16702026/10/06 11:18:51 INFO Received complete multipart upload request method=POST path=/1671=== CONT TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key16722026/10/06 11:18:51 INFO Received request for more parts method=POST path=/1673=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal16742026/10/06 11:18:51 INFO Received uploads request method=POST path=/1675--- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)1676 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1677 --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1678 --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1679 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1680=== CONT TestIsValidUploadKey/narinfo1681=== CONT TestIsValidUploadKey/realisation_plus_in_output1682=== CONT TestIsValidUploadKey/unknown_type1683=== CONT TestIsValidUploadKey/empty_key1684=== CONT TestIsValidUploadKey/absolute1685=== CONT TestIsValidUploadKey/traversal_nar1686=== CONT TestIsValidUploadKey/traversal1687=== CONT TestIsValidUploadKey/listing_key,_narinfo_type1688=== CONT TestIsValidUploadKey/nar_key,_narinfo_type1689=== CONT TestIsValidUploadKey/narinfo_key,_nar_type1690=== CONT TestIsValidUploadKey/index.html1691=== CONT TestIsValidUploadKey/nix-cache-info1692=== CONT TestIsValidUploadKey/build_log_home-manager_file1693=== CONT TestIsValidUploadKey/realisation1694=== CONT TestIsValidUploadKey/build_log_equals1695=== CONT TestIsValidUploadKey/build_log_question_mark1696=== CONT TestIsValidUploadKey/build_log_plus_in_name1697=== CONT TestIsValidUploadKey/nar_plain1698=== CONT TestIsValidUploadKey/build_log1699=== CONT TestIsValidUploadKey/listing1700=== CONT TestIsValidUploadKey/nar_xz1701=== CONT TestIsValidUploadKey/nar_zst1702--- PASS: TestIsValidUploadKey (0.00s)1703 --- PASS: TestIsValidUploadKey/narinfo (0.00s)1704 --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1705 --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1706 --- PASS: TestIsValidUploadKey/empty_key (0.00s)1707 --- PASS: TestIsValidUploadKey/absolute (0.00s)1708 --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1709 --- PASS: TestIsValidUploadKey/traversal (0.00s)1710 --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1711 --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1712 --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1713 --- PASS: TestIsValidUploadKey/index.html (0.00s)1714 --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1715 --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1716 --- PASS: TestIsValidUploadKey/realisation (0.00s)1717 --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1718 --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1719 --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1720 --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1721 --- PASS: TestIsValidUploadKey/build_log (0.00s)1722 --- PASS: TestIsValidUploadKey/listing (0.00s)1723 --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1724 --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1725=== CONT TestPendingClosureWriteTimeout/empty1726=== CONT TestPendingClosureWriteTimeout/670k_objects1727=== CONT TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline17282026/10/06 11:18:51 INFO Upload complete. (150ms)1729=== NAME TestClientMultipleUploads1730 client_integration_test.go:369: Uploaded 3 paths in 182.212ms1731=== RUN TestService_AuthMiddleware_OIDC/valid_OIDC_token1732=== PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1733=== RUN TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1734=== PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1735=== RUN TestService_AuthMiddleware_OIDC/malformed_token_rejected1736=== PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1737=== RUN TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1738=== PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1739=== CONT TestPendingClosureWriteTimeout/400_objects1740=== CONT TestProxyWriteTimeout/narinfo1741=== CONT TestProxyWriteTimeout/10_GiB_nar1742=== CONT TestProxyWriteTimeout/unknown_size1743=== CONT TestProxyWriteTimeout/1_GiB_nar1744--- PASS: TestProxyWriteTimeout (0.00s)1745 --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1746 --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1747 --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1748 --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)1749=== CONT TestIsValidCachePath/narinfo1750=== CONT TestIsValidCachePath/nix-cache-info1751=== CONT TestIsValidCachePath/realisation1752=== CONT TestIsValidCachePath/log1753=== CONT TestIsValidCachePath/ls1754=== CONT TestIsValidCachePath/nar_uncompressed1755=== CONT TestIsValidCachePath/nar_bz21756=== CONT TestIsValidCachePath/nar_xz1757=== CONT TestIsValidCachePath/nar_zst1758=== CONT TestIsValidCachePath/narinfo_all_nix_base32_chars1759=== CONT TestIsValidCachePath/short_hash1760=== CONT TestIsValidCachePath/random_path1761=== CONT TestIsValidCachePath/wrong_extension1762=== CONT TestIsValidCachePath/leading_slash1763=== CONT TestIsValidCachePath/empty1764=== CONT TestIsValidCachePath/invalid_char_e1765=== CONT TestIsValidCachePath/invalid_char_u1766=== CONT TestIsValidCachePath/traversal_in_middle1767=== CONT TestIsValidCachePath/traversal_parent1768=== CONT TestIsValidCachePath/index.html1769--- PASS: TestIsValidCachePath (0.00s)1770 --- PASS: TestIsValidCachePath/narinfo (0.00s)1771 --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1772 --- PASS: TestIsValidCachePath/realisation (0.00s)1773 --- PASS: TestIsValidCachePath/log (0.00s)1774 --- PASS: TestIsValidCachePath/ls (0.00s)1775 --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1776 --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1777 --- PASS: TestIsValidCachePath/nar_xz (0.00s)1778 --- PASS: TestIsValidCachePath/nar_zst (0.00s)1779 --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)1780 --- PASS: TestIsValidCachePath/short_hash (0.00s)1781 --- PASS: TestIsValidCachePath/random_path (0.00s)1782 --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1783 --- PASS: TestIsValidCachePath/leading_slash (0.00s)1784 --- PASS: TestIsValidCachePath/empty (0.00s)1785 --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1786 --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1787 --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1788 --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1789 --- PASS: TestIsValidCachePath/index.html (0.00s)1790=== CONT TestParseSingleRange/none1791=== CONT TestParseSingleRange/open-ended1792=== CONT TestParseSingleRange/start_far_past_EOF1793=== CONT TestParseSingleRange/start_past_EOF1794=== CONT TestParseSingleRange/single_byte1795=== CONT TestParseSingleRange/suffix_exceeds_size1796=== CONT TestParseSingleRange/suffix1797=== CONT TestParseSingleRange/end_clamped_to_size1798=== CONT TestParseSingleRange/malformed_both_empty1799=== CONT TestParseSingleRange/closed1800=== CONT TestParseSingleRange/malformed_end_before_start1801=== CONT TestParseSingleRange/multi-range_ignored1802=== CONT TestParseSingleRange/malformed_no_dash1803=== CONT TestParseSingleRange/unknown_unit1804--- PASS: TestParseSingleRange (0.00s)1805 --- PASS: TestParseSingleRange/none (0.00s)1806 --- PASS: TestParseSingleRange/open-ended (0.00s)1807 --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1808 --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1809 --- PASS: TestParseSingleRange/single_byte (0.00s)1810 --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1811 --- PASS: TestParseSingleRange/suffix (0.00s)1812 --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1813 --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1814 --- PASS: TestParseSingleRange/closed (0.00s)1815 --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1816 --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1817 --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1818 --- PASS: TestParseSingleRange/unknown_unit (0.00s)1819=== CONT TestServerTLSConfig/no_client_CA1820=== CONT TestServerTLSConfig/missing_CA_file1821=== CONT TestServerTLSConfig/not_a_PEM_file1822--- PASS: TestServerTLSConfig (0.00s)1823 --- PASS: TestServerTLSConfig/no_client_CA (0.00s)1824 --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)1825 --- PASS: TestServerTLSConfig/not_a_PEM_file (0.02s)1826=== CONT TestPush_RejectsBadRequests/no_objects18272026/10/06 11:18:51 INFO Received push request method=POST path=/api/pushes1828=== CONT TestPush_RejectsBadRequests/root_not_in_objects18292026/10/06 11:18:51 INFO Received push request method=POST path=/api/pushes1830=== CONT TestPush_RejectsBadRequests/no_roots18312026/10/06 11:18:51 INFO Received push request method=POST path=/api/pushes1832=== CONT TestPush_RejectsBadRequests/bad_root18332026/10/06 11:18:51 INFO Received push request method=POST path=/api/pushes1834=== CONT TestForceGCDuringPushOffersSweptObject/before_pending_rows1835--- PASS: TestPush_RejectsBadRequests (3.69s)1836 --- PASS: TestPush_RejectsBadRequests/no_objects (0.00s)1837 --- PASS: TestPush_RejectsBadRequests/root_not_in_objects (0.00s)1838 --- PASS: TestPush_RejectsBadRequests/no_roots (0.00s)1839 --- PASS: TestPush_RejectsBadRequests/bad_root (0.00s)1840--- PASS: TestConnectWaitsForAPeerMigration (6.54s)1841=== CONT TestForceGCDuringPushOffersSweptObject/after_presence_check1842--- PASS: TestClientMultipleUploads (2.85s)1843=== CONT TestResolveDBConnectionString/flag_wins1844=== CONT TestResolveDBConnectionString/PGHOST_allows_empty1845=== CONT TestResolveDBConnectionString/nothing_configured1846=== CONT TestResolveDBConnectionString/missing_file_is_an_error1847=== CONT TestResolveDBConnectionString/file_when_flag_empty1848=== CONT TestGCEndsOnShutdown/before_the_run1849--- PASS: TestResolveDBConnectionString (0.02s)1850 --- PASS: TestResolveDBConnectionString/flag_wins (0.00s)1851 --- PASS: TestResolveDBConnectionString/PGHOST_allows_empty (0.00s)1852 --- PASS: TestResolveDBConnectionString/nothing_configured (0.00s)1853 --- PASS: TestResolveDBConnectionString/missing_file_is_an_error (0.00s)1854 --- PASS: TestResolveDBConnectionString/file_when_flag_empty (0.00s)18552026/10/06 11:18:51 INFO Aborted multipart uploads count=0 kept=018562026/10/06 11:18:51 WARN Force mode enabled - objects will be deleted immediately without grace period18572026/10/06 11:18:51 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=018582026/10/06 11:18:51 INFO Vacuumed table table=pending_closures18592026/10/06 11:18:51 INFO Vacuumed table table=pending_objects18602026/10/06 11:18:51 INFO Vacuumed table table=multipart_uploads18612026/10/06 11:18:51 INFO Vacuumed table table=closures18622026/10/06 11:18:51 INFO Vacuumed table table=objects1863--- PASS: TestSweepRechecksPendingAfterLocking (2.22s)1864=== CONT TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark1865--- PASS: TestGetExistingObjectsLocksRows (2.17s)1866=== CONT TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete18672026/10/06 11:18:52 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"18682026/10/06 11:18:52 WARN mTLS auth: bound subjects configured but subject DN unavailable18692026/10/06 11:18:52 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1870--- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (2.07s)1871=== CONT TestCacheConfigHandler/full_config,_no_issuer1872=== CONT TestCacheConfigHandler/no_signing_keys1873=== CONT TestCacheConfigHandler/no_cache_url_configured1874=== CONT TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1875--- PASS: TestCacheConfigHandler (0.00s)1876 --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)1877 --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)1878 --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)1879 --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)1880=== CONT TestService_RequireScope_OIDC/builder_may_write1881=== CONT TestService_RequireScope_OIDC/reader_may_read1882=== CONT TestService_RequireScope_OIDC/empty_netrc_password_may_not_read1883=== CONT TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read18842026/10/06 11:18:52 WARN Authentication failed token_preview=wrong token_length=5 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]1885=== CONT TestService_RequireScope_OIDC/static_token_may_admin_with_netrc1886=== CONT TestService_RequireScope_OIDC/reader_may_read_with_netrc1887=== CONT TestService_RequireScope_OIDC/anonymous_may_not_read1888=== CONT TestService_RequireScope_OIDC/writer_implies_read1889=== CONT TestService_RequireScope_OIDC/reader_may_not_write1890=== CONT TestService_RequireScope_OIDC/static_token_may_write1891=== CONT TestService_RequireScope_OIDC/static_token_may_admin1892=== CONT TestService_RequireScope_OIDC/ops_may_admin1893=== CONT TestService_RequireScope_OIDC/ops_may_not_write1894=== CONT TestService_RequireScope_OIDC/builder_may_not_admin1895=== CONT TestClientErrorHandling/InvalidStorePath1896--- PASS: TestService_RequireScope_OIDC (1.50s)1897 --- PASS: TestService_RequireScope_OIDC/builder_may_write (0.00s)1898 --- PASS: TestService_RequireScope_OIDC/reader_may_read (0.00s)1899 --- PASS: TestService_RequireScope_OIDC/empty_netrc_password_may_not_read (0.00s)1900 --- PASS: TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read (0.00s)1901 --- PASS: TestService_RequireScope_OIDC/static_token_may_admin_with_netrc (0.00s)1902 --- PASS: TestService_RequireScope_OIDC/reader_may_read_with_netrc (0.00s)1903 --- PASS: TestService_RequireScope_OIDC/anonymous_may_not_read (0.00s)1904 --- PASS: TestService_RequireScope_OIDC/writer_implies_read (0.00s)1905 --- PASS: TestService_RequireScope_OIDC/reader_may_not_write (0.00s)1906 --- PASS: TestService_RequireScope_OIDC/static_token_may_write (0.00s)1907 --- PASS: TestService_RequireScope_OIDC/static_token_may_admin (0.00s)1908 --- PASS: TestService_RequireScope_OIDC/ops_may_admin (0.00s)1909 --- PASS: TestService_RequireScope_OIDC/ops_may_not_write (0.00s)1910 --- PASS: TestService_RequireScope_OIDC/builder_may_not_admin (0.00s)19112026/10/06 11:18:52 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=3 objects_failed=01912=== NAME TestClientIntegration1913 client_integration_test.go:323: Objects in database after GC:1914 client_integration_test.go:323: Successfully deleted all objects with GC --force1915--- PASS: TestClientIntegration (4.57s)1916=== CONT TestClientErrorHandling/ServerNotAvailable1917--- PASS: TestService_AuthMiddleware_MTLSProxyHeader (2.19s)1918=== CONT TestClientErrorHandling/InvalidAuthToken19192026/10/06 11:18:53 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present1920=== NAME TestPinProtectsFromGC1921 client_integration_test.go:840: Pin successfully protected closure from garbage collection19222026/10/06 11:18:53 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=203.64235ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present1923--- PASS: TestPinProtectsFromGC (6.22s)1924=== CONT TestService_AuthMiddleware_OIDC/valid_OIDC_token1925=== CONT TestService_AuthMiddleware_OIDC/malformed_token_rejected19262026/10/06 11:18:53 WARN Authentication failed token_preview=not-a-valid-jwt token_length=15 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]1927=== CONT TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1928=== CONT TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected19292026/10/06 11:18:53 WARN Authentication failed token_preview=eyJhbGciOi...50bbGogj7g token_length=701 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]1930--- PASS: TestService_AuthMiddleware_OIDC (2.11s)1931 --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1932 --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1933 --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1934 --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)19352026/10/06 11:18:53 INFO Received uploads request method=POST path=/api/pending_closures19362026/10/06 11:18:53 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=428.917758ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19372026/10/06 11:18:53 INFO Aborted multipart uploads count=0 kept=019382026/10/06 11:18:53 WARN Force mode enabled - objects will be deleted immediately without grace period19392026/10/06 11:18:53 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=1 objects-deleted-after-grace-period=1 objects-failed-to-delete=019402026/10/06 11:18:53 INFO Vacuumed table table=pending_closures19412026/10/06 11:18:53 INFO Vacuumed table table=pending_objects19422026/10/06 11:18:53 INFO Vacuumed table table=multipart_uploads19432026/10/06 11:18:53 INFO Vacuumed table table=closures19442026/10/06 11:18:53 INFO Vacuumed table table=objects19452026/10/06 11:18:53 INFO Received uploads request method=POST path=/api/pending_closures19462026/10/06 11:18:53 INFO Aborted multipart uploads count=0 kept=019472026/10/06 11:18:53 WARN Force mode enabled - objects will be deleted immediately without grace period19482026/10/06 11:18:53 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=019492026/10/06 11:18:53 INFO Vacuumed table table=pending_closures19502026/10/06 11:18:53 INFO Vacuumed table table=pending_objects19512026/10/06 11:18:53 INFO Vacuumed table table=multipart_uploads19522026/10/06 11:18:53 INFO Vacuumed table table=closures19532026/10/06 11:18:53 INFO Vacuumed table table=objects19542026/10/06 11:18:53 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=859.050048ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present1955--- PASS: TestForceGCDuringPushOffersSweptObject (0.00s)1956 --- PASS: TestForceGCDuringPushOffersSweptObject/before_pending_rows (2.40s)1957 --- PASS: TestForceGCDuringPushOffersSweptObject/after_presence_check (2.61s)19582026/10/06 11:18:54 INFO Received uploads request method=POST path=/api/pending_closures19592026/10/06 11:18:54 INFO Aborted multipart uploads count=0 kept=019602026/10/06 11:18:54 WARN Force mode enabled - objects will be deleted immediately without grace period19612026/10/06 11:18:54 ERROR failed to remove object object=nar/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.nar.zst error="Post \"http://localhost:54681/bucket89/?delete=\": context canceled"19622026/10/06 11:18:54 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.701137913s error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19632026/10/06 11:18:54 INFO Aborted multipart uploads count=0 kept=019642026/10/06 11:18:54 WARN Force mode enabled - objects will be deleted immediately without grace period19652026-10-06 11:18:54.934 UTC [38644] ERROR: canceling statement due to user request19662026-10-06 11:18:54.934 UTC [38644] STATEMENT: -- name: MarkStaleObjects :execrows1967 WITH RECURSIVE ct AS (1968 SELECT timezone('UTC', now()) AS now1969 ),1970 closure_reach AS (1971 -- Start with all closure keys1972 SELECT o.key, o.refs1973 FROM objects o1974 INNER JOIN closures c ON o.key = c.key1975 UNION1976 -- Recursively add all referenced objects1977 SELECT o.key, o.refs1978 FROM objects o1979 INNER JOIN closure_reach cr ON o.key = ANY(cr.refs)1980 ),1981 reachable_objects AS (1982 SELECT DISTINCT key FROM closure_reach1983 ),1984 stale_objects AS (1985 SELECT o.key1986 FROM objects AS o, ct1987 WHERE1988 NOT EXISTS (1989 SELECT 11990 FROM reachable_objects ro1991 WHERE ro.key = o.key1992 )1993 AND NOT EXISTS (1994 SELECT 11995 FROM pending_objects AS po1996 WHERE po.key = o.key1997 )1998 AND o.deleted_at IS NULL -- Only mark fresh objects1999 ORDER BY o.key COLLATE "C" -- lock in key order, like commit_pending_closure2000 FOR UPDATE2001 )2002 UPDATE objects2003 SET2004 deleted_at = ct.now,2005 first_deleted_at = COALESCE(first_deleted_at, ct.now)2006 FROM stale_objects, ct2007 WHERE objects.key = stale_objects.key2008 2009--- PASS: TestGCEndsOnShutdown (0.00s)2010 --- PASS: TestGCEndsOnShutdown/before_the_run (2.72s)2011 --- PASS: TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete (2.68s)2012 --- PASS: TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark (3.07s)2013--- PASS: TestPendingClosureWriteTimeout (0.00s)2014 --- PASS: TestPendingClosureWriteTimeout/empty (0.00s)2015 --- PASS: TestPendingClosureWriteTimeout/670k_objects (0.00s)2016 --- PASS: TestPendingClosureWriteTimeout/400_objects (0.00s)2017 --- PASS: TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline (3.89s)20182026/10/06 11:18:55 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"20192026/10/06 11:18:55 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"20202026/10/06 11:18:56 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20212026/10/06 11:18:56 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=197.65022ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20222026/10/06 11:18:56 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=364.561201ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20232026/10/06 11:18:57 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=731.140671ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20242026/10/06 11:18:58 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.685900397s error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20252026/10/06 11:18:59 WARN Failed to fetch cache config, uploading without size limit error="fetching cache config: sending request: request failed after retries: Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused"20262026/10/06 11:18:59 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20272026/10/06 11:18:59 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=184.023242ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20282026/10/06 11:19:00 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=398.591622ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20292026/10/06 11:19:00 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=866.715711ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20302026/10/06 11:19:01 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.647650624s error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20312026/10/06 11:19:02 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures20322026/10/06 11:19:03 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=211.790387ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures20332026/10/06 11:19:03 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=404.958131ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures20342026/10/06 11:19:03 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=841.198343ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures20352026/10/06 11:19:04 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.539577975s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures2036--- PASS: TestClientErrorHandling (0.00s)2037 --- PASS: TestClientErrorHandling/InvalidStorePath (2.70s)2038 --- PASS: TestClientErrorHandling/InvalidAuthToken (2.21s)2039 --- PASS: TestClientErrorHandling/ServerNotAvailable (13.10s)2040PASS2041{"timestamp":"2026-10-06T11:19:06.0641Z","level":"ERROR","message":"HTTP transport failed","event":"http_transport_failed","component":"server","subsystem":"transport","peer_addr":"127.0.0.1:54762","error_kind":"io_error","error":"Cancelled","result":"transport_error","target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":2354,"threadName":"rustfs-worker","threadId":"ThreadId(4)"}20422026-10-06 11:19:06.221 UTC [38184] LOG: received smart shutdown request20432026-10-06 11:19:06.222 UTC [38184] LOG: background worker "logical replication launcher" (PID 38194) exited with exit code 120442026-10-06 11:19:06.230 UTC [38189] LOG: shutting down20452026-10-06 11:19:06.230 UTC [38189] LOG: checkpoint starting: shutdown immediate20462026-10-06 11:19:07.500 UTC [38189] LOG: checkpoint complete: wrote 12487 buffers (76.2%), wrote 4 SLRU buffers; 0 WAL file(s) added, 0 removed, 27 recycled; write=0.693 s, sync=0.534 s, total=1.271 s; sync files=31335, longest=0.001 s, average=0.001 s; distance=440366 kB, estimate=440366 kB; lsn=0/1C598B40, redo lsn=0/1C598B4020472026-10-06 11:19:07.506 UTC [38184] LOG: database system is shut down2048Running OIDC tests...2049=== RUN TestAudienceForIssuer2050=== PAUSE TestAudienceForIssuer2051=== RUN TestGlobMatch2052=== PAUSE TestGlobMatch2053=== RUN TestValidateToken_ValidToken2054=== PAUSE TestValidateToken_ValidToken2055=== RUN TestValidateToken_WrongAudience2056=== PAUSE TestValidateToken_WrongAudience2057=== RUN TestValidateToken_Expired2058=== PAUSE TestValidateToken_Expired2059=== RUN TestValidateToken_BoundClaimsMismatch2060=== PAUSE TestValidateToken_BoundClaimsMismatch2061=== RUN TestValidateToken_BoundSubjectMismatch2062=== PAUSE TestValidateToken_BoundSubjectMismatch2063=== RUN TestValidateToken_MultipleProviders2064=== PAUSE TestValidateToken_MultipleProviders2065=== RUN TestValidateToken_NoMatchingProvider2066=== PAUSE TestValidateToken_NoMatchingProvider2067=== RUN TestValidateToken_KubernetesServiceAccount2068=== PAUSE TestValidateToken_KubernetesServiceAccount2069=== RUN TestNewValidator_KubernetesRequiresCA2070=== PAUSE TestNewValidator_KubernetesRequiresCA2071=== RUN TestValidateToken_KubernetesIssuerFromOwnToken2072=== PAUSE TestValidateToken_KubernetesIssuerFromOwnToken2073=== RUN TestPins_ReservedForMatchingRule2074=== PAUSE TestPins_ReservedForMatchingRule2075=== RUN TestPins_TopLevelShorthand2076=== PAUSE TestPins_TopLevelShorthand2077=== RUN TestPins_ConfigValidation2078=== PAUSE TestPins_ConfigValidation2079=== RUN TestScopes_LegacyProviderDefaultsToWrite2080=== PAUSE TestScopes_LegacyProviderDefaultsToWrite2081=== RUN TestScopes_Rules2082=== PAUSE TestScopes_Rules2083=== RUN TestScopes_ConfigValidation2084=== PAUSE TestScopes_ConfigValidation2085=== CONT TestAudienceForIssuer2086--- PASS: TestAudienceForIssuer (0.00s)2087=== CONT TestNewValidator_KubernetesRequiresCA2088=== CONT TestScopes_LegacyProviderDefaultsToWrite2089=== CONT TestValidateToken_KubernetesIssuerFromOwnToken2090=== CONT TestPins_TopLevelShorthand2091=== CONT TestValidateToken_BoundClaimsMismatch2092=== CONT TestValidateToken_WrongAudience2093=== CONT TestValidateToken_ValidToken2094=== CONT TestValidateToken_NoMatchingProvider2095=== CONT TestValidateToken_MultipleProviders2096=== CONT TestValidateToken_KubernetesServiceAccount20972026/10/06 11:19:08 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:55134/oidc2098--- PASS: TestPins_TopLevelShorthand (0.04s)2099=== CONT TestScopes_ConfigValidation2100--- PASS: TestScopes_ConfigValidation (0.00s)2101=== CONT TestScopes_Rules21022026/10/06 11:19:08 INFO OIDC provider initialized name=kubernetes issuer=https://127.0.0.1:551362103--- PASS: TestValidateToken_KubernetesServiceAccount (0.04s)2104=== CONT TestValidateToken_Expired21052026/10/06 11:19:08 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:55138/oidc2106--- PASS: TestValidateToken_BoundClaimsMismatch (0.06s)2107=== CONT TestPins_ConfigValidation2108--- PASS: TestPins_ConfigValidation (0.00s)2109=== CONT TestValidateToken_BoundSubjectMismatch21102026/10/06 11:19:08 http: TLS handshake error from 127.0.0.1:55141: read tcp 127.0.0.1:55140->127.0.0.1:55141: use of closed network connection2111--- PASS: TestNewValidator_KubernetesRequiresCA (0.07s)2112=== CONT TestPins_ReservedForMatchingRule21132026/10/06 11:19:08 INFO OIDC provider initialized name=kubernetes issuer=https://oidc.eks.invalid/id/ABC12321142026/10/06 11:19:08 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:55143/oidc2115--- PASS: TestScopes_LegacyProviderDefaultsToWrite (0.08s)2116=== CONT TestGlobMatch2117=== RUN TestGlobMatch/foo_foo2118=== PAUSE TestGlobMatch/foo_foo2119=== RUN TestGlobMatch/foo_bar2120=== PAUSE TestGlobMatch/foo_bar2121=== RUN TestGlobMatch/*_2122=== PAUSE TestGlobMatch/*_2123=== RUN TestGlobMatch/*_anything2124=== PAUSE TestGlobMatch/*_anything2125=== RUN TestGlobMatch/foo*_foo2126=== PAUSE TestGlobMatch/foo*_foo2127=== RUN TestGlobMatch/foo*_foobar2128=== PAUSE TestGlobMatch/foo*_foobar2129=== RUN TestGlobMatch/foo*_bar2130=== PAUSE TestGlobMatch/foo*_bar2131=== RUN TestGlobMatch/*bar_bar2132=== PAUSE TestGlobMatch/*bar_bar2133=== RUN TestGlobMatch/*bar_foobar2134=== PAUSE TestGlobMatch/*bar_foobar2135=== RUN TestGlobMatch/*bar_foo2136=== PAUSE TestGlobMatch/*bar_foo2137=== RUN TestGlobMatch/foo*bar_foobar2138=== PAUSE TestGlobMatch/foo*bar_foobar2139=== RUN TestGlobMatch/foo*bar_foo123bar2140=== PAUSE TestGlobMatch/foo*bar_foo123bar2141=== RUN TestGlobMatch/foo*bar_foobarbaz2142=== PAUSE TestGlobMatch/foo*bar_foobarbaz2143=== RUN TestGlobMatch/*/*_foo/bar2144=== PAUSE TestGlobMatch/*/*_foo/bar2145=== RUN TestGlobMatch/*/*_foo2146=== PAUSE TestGlobMatch/*/*_foo2147=== RUN TestGlobMatch/refs/heads/*_refs/heads/main2148=== PAUSE TestGlobMatch/refs/heads/*_refs/heads/main2149=== RUN TestGlobMatch/refs/heads/*_refs/tags/v1.02150=== PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.02151=== RUN TestGlobMatch/refs/*/main_refs/heads/main2152=== PAUSE TestGlobMatch/refs/*/main_refs/heads/main2153=== RUN TestGlobMatch/fo?_foo2154=== PAUSE TestGlobMatch/fo?_foo2155=== RUN TestGlobMatch/fo?_fo2156=== PAUSE TestGlobMatch/fo?_fo2157=== RUN TestGlobMatch/fo?_fooo2158=== PAUSE TestGlobMatch/fo?_fooo2159=== RUN TestGlobMatch/?oo_foo2160=== PAUSE TestGlobMatch/?oo_foo2161=== RUN TestGlobMatch/?oo_boo2162=== PAUSE TestGlobMatch/?oo_boo2163=== RUN TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2164=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2165=== RUN TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2166=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2167=== CONT TestGlobMatch/foo_foo2168=== CONT TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2169=== CONT TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2170=== CONT TestGlobMatch/?oo_boo2171=== CONT TestGlobMatch/?oo_foo2172=== CONT TestGlobMatch/fo?_fooo2173=== CONT TestGlobMatch/fo?_fo2174=== CONT TestGlobMatch/fo?_foo2175=== CONT TestGlobMatch/refs/*/main_refs/heads/main2176=== CONT TestGlobMatch/refs/heads/*_refs/tags/v1.02177=== CONT TestGlobMatch/refs/heads/*_refs/heads/main2178=== CONT TestGlobMatch/*/*_foo2179=== CONT TestGlobMatch/*/*_foo/bar2180=== CONT TestGlobMatch/foo*bar_foobarbaz2181=== CONT TestGlobMatch/foo*bar_foo123bar2182=== CONT TestGlobMatch/foo*bar_foobar2183=== CONT TestGlobMatch/*bar_foo2184=== CONT TestGlobMatch/*bar_foobar2185=== CONT TestGlobMatch/*bar_bar2186=== CONT TestGlobMatch/foo*_bar2187=== CONT TestGlobMatch/foo*_foobar2188=== CONT TestGlobMatch/foo*_foo2189=== CONT TestGlobMatch/*_anything2190=== CONT TestGlobMatch/*_2191=== CONT TestGlobMatch/foo_bar2192--- PASS: TestGlobMatch (0.00s)2193 --- PASS: TestGlobMatch/foo_foo (0.00s)2194 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)2195 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)2196 --- PASS: TestGlobMatch/?oo_boo (0.00s)2197 --- PASS: TestGlobMatch/?oo_foo (0.00s)2198 --- PASS: TestGlobMatch/fo?_fooo (0.00s)2199 --- PASS: TestGlobMatch/fo?_fo (0.00s)2200 --- PASS: TestGlobMatch/fo?_foo (0.00s)2201 --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)2202 --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)2203 --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)2204 --- PASS: TestGlobMatch/*/*_foo (0.00s)2205 --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)2206 --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)2207 --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)2208 --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)2209 --- PASS: TestGlobMatch/*bar_foo (0.00s)2210 --- PASS: TestGlobMatch/*bar_foobar (0.00s)2211 --- PASS: TestGlobMatch/*bar_bar (0.00s)2212 --- PASS: TestGlobMatch/foo*_bar (0.00s)2213 --- PASS: TestGlobMatch/foo*_foobar (0.00s)2214 --- PASS: TestGlobMatch/foo*_foo (0.00s)2215 --- PASS: TestGlobMatch/*_anything (0.00s)2216 --- PASS: TestGlobMatch/*_ (0.00s)2217 --- PASS: TestGlobMatch/foo_bar (0.00s)2218--- PASS: TestValidateToken_KubernetesIssuerFromOwnToken (0.08s)22192026/10/06 11:19:09 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:55147/oidc2220--- PASS: TestScopes_Rules (0.06s)22212026/10/06 11:19:09 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:55150/oidc2222--- PASS: TestValidateToken_Expired (0.06s)22232026/10/06 11:19:09 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:55152/oidc2224--- PASS: TestValidateToken_ValidToken (0.12s)22252026/10/06 11:19:09 INFO OIDC provider initialized name=provider1 issuer=http://127.0.0.1:55142/oidc2226--- PASS: TestValidateToken_NoMatchingProvider (0.13s)22272026/10/06 11:19:09 INFO OIDC provider initialized name=provider1 issuer=http://127.0.0.1:55149/oidc22282026/10/06 11:19:09 INFO OIDC provider initialized name=provider2 issuer=http://127.0.0.1:55156/oidc2229--- PASS: TestValidateToken_MultipleProviders (0.13s)22302026/10/06 11:19:09 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:55159/oidc2231--- PASS: TestValidateToken_WrongAudience (0.14s)22322026/10/06 11:19:09 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:55161/oidc2233--- PASS: TestValidateToken_BoundSubjectMismatch (0.08s)22342026/10/06 11:19:09 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:55163/oidc2235--- PASS: TestPins_ReservedForMatchingRule (0.09s)2236PASS2237Running hook tests...2238=== RUN TestSendPathsEmpty2239=== PAUSE TestSendPathsEmpty2240=== RUN TestQueueEnqueueAndFetch2241=== PAUSE TestQueueEnqueueAndFetch2242=== RUN TestQueueDeduplication2243=== PAUSE TestQueueDeduplication2244=== RUN TestQueueRemove2245=== PAUSE TestQueueRemove2246=== RUN TestQueueFetchBatchLimit2247=== PAUSE TestQueueFetchBatchLimit2248=== RUN TestQueueRetryMovesToBack2249=== PAUSE TestQueueRetryMovesToBack2250=== RUN TestQueueFetchRemoveLifecycle2251=== PAUSE TestQueueFetchRemoveLifecycle2252=== RUN TestQueueConcurrentWriters2253=== PAUSE TestQueueConcurrentWriters2254=== RUN TestQueueRemoveLargeClosure2255=== PAUSE TestQueueRemoveLargeClosure2256=== RUN TestServerClientIntegration2257=== PAUSE TestServerClientIntegration2258=== RUN TestServerQueueError2259=== PAUSE TestServerQueueError2260=== RUN TestGetListenerSocketActivation2261 server_test.go:210: === RUN TestGetListenerSocketActivation2262 --- PASS: TestGetListenerSocketActivation (0.00s)2263 PASS2264 2265--- PASS: TestGetListenerSocketActivation (0.01s)2266=== RUN TestDrainIsolatesPoisonPath2267=== PAUSE TestDrainIsolatesPoisonPath2268=== RUN TestRunNotBlockedByPoisonHead2269=== PAUSE TestRunNotBlockedByPoisonHead2270=== RUN TestDrainGivesUpWhenServerDown2271=== PAUSE TestDrainGivesUpWhenServerDown2272=== RUN TestFailedPathPrunedByLaterClosure2273=== PAUSE TestFailedPathPrunedByLaterClosure2274=== RUN TestWorkerUploadsAndRemoves2275=== PAUSE TestWorkerUploadsAndRemoves2276=== RUN TestWorkerSkipsGCdPaths2277=== PAUSE TestWorkerSkipsGCdPaths2278=== RUN TestWorkerPrunesClosureDeps2279=== PAUSE TestWorkerPrunesClosureDeps2280=== RUN TestDrainTimeout2281=== PAUSE TestDrainTimeout2282=== CONT TestWorkerSkipsGCdPaths2283=== CONT TestSendPathsEmpty2284--- PASS: TestSendPathsEmpty (0.00s)2285=== CONT TestWorkerUploadsAndRemoves2286=== CONT TestQueueDeduplication2287=== CONT TestFailedPathPrunedByLaterClosure2288=== CONT TestDrainGivesUpWhenServerDown2289=== CONT TestRunNotBlockedByPoisonHead2290=== CONT TestDrainIsolatesPoisonPath2291=== CONT TestQueueRetryMovesToBack2292=== CONT TestQueueFetchBatchLimit2293=== CONT TestQueueRemove22942026/10/06 11:19:09 INFO Upload queue status pending=22295--- PASS: TestQueueFetchBatchLimit (0.01s)2296=== CONT TestQueueEnqueueAndFetch22972026/10/06 11:19:09 INFO Upload queue status pending=222982026/10/06 11:19:09 INFO Uploading batch count=222992026/10/06 11:19:09 INFO Uploading batch count=123002026/10/06 11:19:09 ERROR Upload failed error="upload failed" count=123012026/10/06 11:19:09 WARN Store path no longer exists (garbage collected?), removing from queue path=/nix/var/nix/builds/nix-38141-2789532961/TestWorkerSkipsGCdPaths232260210/002/nonexistent23022026/10/06 11:19:09 INFO Upload queue status pending=323032026/10/06 11:19:09 INFO Uploading batch count=123042026/10/06 11:19:09 ERROR Upload failed error="upload failed" count=123052026/10/06 11:19:09 INFO Uploading batch count=123062026/10/06 11:19:09 INFO Uploading batch count=123072026/10/06 11:19:09 INFO Uploading batch count=423082026/10/06 11:19:09 ERROR Upload failed error="upload failed" count=423092026/10/06 11:19:09 INFO Uploading batch count=223102026/10/06 11:19:09 ERROR Upload failed error="upload failed" count=223112026/10/06 11:19:09 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-38141-2789532961/TestDrainGivesUpWhenServerDown2659498359/002/a2312--- PASS: TestQueueRetryMovesToBack (0.01s)2313=== CONT TestDrainTimeout23142026/10/06 11:19:09 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-38141-2789532961/TestDrainIsolatesPoisonPath1972725231/002/bbb23152026/10/06 11:19:09 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-38141-2789532961/TestDrainGivesUpWhenServerDown2659498359/002/b23162026/10/06 11:19:09 INFO Uploading batch count=12317--- PASS: TestQueueRemove (0.01s)2318=== CONT TestWorkerPrunesClosureDeps23192026/10/06 11:19:09 INFO Uploading batch count=223202026/10/06 11:19:09 ERROR Upload failed error="upload failed" count=223212026/10/06 11:19:09 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-38141-2789532961/TestDrainGivesUpWhenServerDown2659498359/002/c2322--- PASS: TestQueueDeduplication (0.01s)2323=== CONT TestServerClientIntegration23242026/10/06 11:19:09 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-38141-2789532961/TestDrainGivesUpWhenServerDown2659498359/002/d23252026/10/06 11:19:09 INFO Uploading batch count=223262026/10/06 11:19:09 ERROR Upload failed error="upload failed" count=223272026/10/06 11:19:09 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-38141-2789532961/TestDrainGivesUpWhenServerDown2659498359/002/e23282026/10/06 11:19:09 INFO Uploading batch count=123292026/10/06 11:19:09 ERROR Upload failed error="upload failed" count=12330--- PASS: TestServerClientIntegration (0.00s)2331=== CONT TestServerQueueError23322026/10/06 11:19:09 ERROR Upload failed, will retry later error="upload failed" path=/nix/var/nix/builds/nix-38141-2789532961/TestDrainGivesUpWhenServerDown2659498359/002/f23332026/10/06 11:19:09 ERROR Failed to queue paths error="permission denied" count=123342026/10/06 11:19:09 INFO Uploading batch count=123352026/10/06 11:19:09 ERROR Upload failed error="upload failed" count=12336--- PASS: TestFailedPathPrunedByLaterClosure (0.01s)2337=== CONT TestQueueRemoveLargeClosure23382026/10/06 11:19:09 ERROR Drain finished with paths left in queue remaining=1023392026/10/06 11:19:09 INFO Uploading batch count=123402026/10/06 11:19:09 ERROR Upload failed error="upload failed" count=12341--- PASS: TestServerQueueError (0.00s)2342=== CONT TestQueueConcurrentWriters23432026/10/06 11:19:09 ERROR Drain finished with paths left in queue remaining=123442026/10/06 11:19:09 INFO Uploading batch count=223452026/10/06 11:19:09 INFO Upload queue status pending=223462026/10/06 11:19:09 INFO Uploading batch count=12347--- PASS: TestDrainGivesUpWhenServerDown (0.01s)2348=== CONT TestQueueFetchRemoveLifecycle2349--- PASS: TestQueueEnqueueAndFetch (0.01s)2350--- PASS: TestDrainIsolatesPoisonPath (0.02s)2351--- PASS: TestQueueFetchRemoveLifecycle (0.00s)2352--- PASS: TestWorkerUploadsAndRemoves (0.03s)2353--- PASS: TestWorkerSkipsGCdPaths (0.03s)2354--- PASS: TestWorkerPrunesClosureDeps (0.03s)2355--- PASS: TestQueueRemoveLargeClosure (0.03s)2356--- PASS: TestQueueConcurrentWriters (0.15s)23572026/10/06 11:19:09 ERROR Upload failed error="context deadline exceeded" count=223582026/10/06 11:19:09 ERROR Drain finished with paths left in queue remaining=42359--- PASS: TestDrainTimeout (0.21s)23602026/10/06 11:19:10 INFO Uploading batch count=123612026/10/06 11:19:10 INFO Uploading batch count=123622026/10/06 11:19:10 INFO Uploading batch count=123632026/10/06 11:19:10 ERROR Upload failed error="upload failed" count=123642026/10/06 11:19:10 INFO Uploading batch count=123652026/10/06 11:19:10 ERROR Upload failed error="upload failed" count=123662026/10/06 11:19:10 INFO Uploading batch count=123672026/10/06 11:19:10 ERROR Upload failed error="upload failed" count=123682026/10/06 11:19:10 INFO Uploading batch count=123692026/10/06 11:19:10 ERROR Upload failed error="upload failed" count=123702026/10/06 11:19:10 ERROR Drain finished with paths left in queue remaining=12371--- PASS: TestRunNotBlockedByPoisonHead (1.02s)2372PASS2373Running signing tests...2374=== RUN TestExternalSigner2375=== PAUSE TestExternalSigner2376=== RUN TestExternalSignerWrongPublicKey2377=== PAUSE TestExternalSignerWrongPublicKey2378=== RUN TestExternalSignerErrors2379=== PAUSE TestExternalSignerErrors2380=== RUN TestExternalSignerRestartsAfterCrash2381=== PAUSE TestExternalSignerRestartsAfterCrash2382=== RUN TestExternalSignerProgramErrorKeepsProcess2383=== PAUSE TestExternalSignerProgramErrorKeepsProcess2384=== RUN TestExternalSignerClose2385=== PAUSE TestExternalSignerClose2386=== RUN TestExternalSignerRejectsInvalidPublicKey2387=== PAUSE TestExternalSignerRejectsInvalidPublicKey2388=== RUN TestExternalSignerCancellation2389=== PAUSE TestExternalSignerCancellation2390=== RUN TestGenerateFingerprint2391=== PAUSE TestGenerateFingerprint2392=== RUN TestParseSigningKey2393=== PAUSE TestParseSigningKey2394=== RUN TestSign2395=== PAUSE TestSign2396=== RUN TestSignBatch2397=== PAUSE TestSignBatch2398=== RUN TestNilKeyPublicKey2399=== PAUSE TestNilKeyPublicKey2400=== RUN TestSignErrors2401=== PAUSE TestSignErrors2402=== CONT TestExternalSigner2403=== CONT TestExternalSignerCancellation2404=== CONT TestSignBatch2405=== CONT TestParseSigningKey2406=== CONT TestExternalSignerProgramErrorKeepsProcess2407=== RUN TestParseSigningKey/valid_32-byte_key2408=== PAUSE TestParseSigningKey/valid_32-byte_key2409=== RUN TestParseSigningKey/valid_32-byte_key_with_different_name2410=== CONT TestSign2411=== CONT TestSignErrors2412=== CONT TestExternalSignerClose2413=== CONT TestExternalSignerRejectsInvalidPublicKey2414=== RUN TestExternalSignerRejectsInvalidPublicKey/#002415=== PAUSE TestExternalSignerRejectsInvalidPublicKey/#002416=== RUN TestExternalSignerRejectsInvalidPublicKey/no-colon2417=== PAUSE TestExternalSignerRejectsInvalidPublicKey/no-colon2418=== RUN TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=2419=== PAUSE TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=2420=== RUN TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!2421=== PAUSE TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!2422=== RUN TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==2423=== PAUSE TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==2424=== CONT TestExternalSignerRejectsInvalidPublicKey/#002425=== CONT TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==2426=== CONT TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!2427=== CONT TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=2428=== CONT TestExternalSignerErrors2429=== RUN TestExternalSignerErrors/missing_signature_name2430=== PAUSE TestParseSigningKey/valid_32-byte_key_with_different_name2431=== RUN TestParseSigningKey/no_colon2432=== PAUSE TestParseSigningKey/no_colon2433=== RUN TestParseSigningKey/empty_name2434=== CONT TestGenerateFingerprint2435=== RUN TestGenerateFingerprint/basic_with_references2436=== PAUSE TestGenerateFingerprint/basic_with_references2437=== RUN TestGenerateFingerprint/no_references2438=== PAUSE TestGenerateFingerprint/no_references2439=== PAUSE TestExternalSignerErrors/missing_signature_name2440=== RUN TestGenerateFingerprint/unsorted_references_get_sorted2441=== PAUSE TestGenerateFingerprint/unsorted_references_get_sorted2442=== PAUSE TestParseSigningKey/empty_name2443=== RUN TestExternalSignerErrors/program_error2444=== RUN TestGenerateFingerprint/invalid_nar_hash_prefix2445=== PAUSE TestExternalSignerErrors/program_error2446=== PAUSE TestGenerateFingerprint/invalid_nar_hash_prefix2447=== RUN TestParseSigningKey/invalid_base642448=== PAUSE TestParseSigningKey/invalid_base642449=== RUN TestGenerateFingerprint/invalid_nar_hash_length2450=== PAUSE TestGenerateFingerprint/invalid_nar_hash_length2451=== RUN TestExternalSignerErrors/null2452=== RUN TestGenerateFingerprint/invalid_store_path_prefix2453=== PAUSE TestGenerateFingerprint/invalid_store_path_prefix2454=== PAUSE TestExternalSignerErrors/null2455=== RUN TestParseSigningKey/wrong_length2456=== RUN TestGenerateFingerprint/invalid_reference_prefix2457=== PAUSE TestGenerateFingerprint/invalid_reference_prefix2458=== PAUSE TestParseSigningKey/wrong_length2459=== CONT TestExternalSignerRestartsAfterCrash2460=== CONT TestExternalSignerWrongPublicKey2461=== RUN TestExternalSignerErrors/wrong_id2462=== PAUSE TestExternalSignerErrors/wrong_id2463=== RUN TestExternalSignerErrors/missing_signatures2464=== PAUSE TestExternalSignerErrors/missing_signatures2465=== RUN TestExternalSignerErrors/null_signatures2466=== PAUSE TestExternalSignerErrors/null_signatures2467=== RUN TestExternalSignerErrors/wrong_signatures_shape2468=== PAUSE TestExternalSignerErrors/wrong_signatures_shape2469=== RUN TestExternalSignerErrors/unrequested_key2470=== PAUSE TestExternalSignerErrors/unrequested_key2471=== RUN TestExternalSignerErrors/invalid_signature_base642472=== PAUSE TestExternalSignerErrors/invalid_signature_base642473=== RUN TestExternalSignerErrors/exit_at_startup2474=== PAUSE TestExternalSignerErrors/exit_at_startup2475=== RUN TestExternalSignerErrors/too_few_signatures2476=== PAUSE TestExternalSignerErrors/too_few_signatures2477=== RUN TestExternalSignerErrors/wrong_signature_name2478=== PAUSE TestExternalSignerErrors/wrong_signature_name2479=== RUN TestExternalSignerErrors/wrong_signature_length2480=== PAUSE TestExternalSignerErrors/wrong_signature_length2481=== RUN TestExternalSignerErrors/exit_on_request2482=== PAUSE TestExternalSignerErrors/exit_on_request2483=== RUN TestExternalSignerErrors/invalid_JSON2484=== PAUSE TestExternalSignerErrors/invalid_JSON2485=== RUN TestExternalSignerErrors/wrong_shape2486=== PAUSE TestExternalSignerErrors/wrong_shape2487=== CONT TestNilKeyPublicKey2488--- PASS: TestNilKeyPublicKey (0.00s)2489=== CONT TestExternalSignerRejectsInvalidPublicKey/no-colon2490--- PASS: TestExternalSignerRejectsInvalidPublicKey (0.00s)2491 --- PASS: TestExternalSignerRejectsInvalidPublicKey/#00 (0.00s)2492 --- PASS: TestExternalSignerRejectsInvalidPublicKey/test-key:YQ== (0.00s)2493 --- PASS: TestExternalSignerRejectsInvalidPublicKey/test-key:invalid! (0.00s)2494 --- PASS: TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA= (0.00s)2495 --- PASS: TestExternalSignerRejectsInvalidPublicKey/no-colon (0.00s)2496=== CONT TestGenerateFingerprint/basic_with_references2497=== CONT TestParseSigningKey/valid_32-byte_key2498=== RUN TestSignErrors/nil_key2499=== CONT TestParseSigningKey/invalid_base642500=== CONT TestGenerateFingerprint/no_references2501=== PAUSE TestSignErrors/nil_key2502=== RUN TestSignErrors/nil_narinfo2503=== PAUSE TestSignErrors/nil_narinfo2504=== RUN TestSignErrors/invalid_narinfo2505=== PAUSE TestSignErrors/invalid_narinfo2506=== RUN TestSignErrors/canceled2507=== PAUSE TestSignErrors/canceled2508=== CONT TestGenerateFingerprint/invalid_store_path_prefix2509--- PASS: TestSign (0.00s)2510=== CONT TestGenerateFingerprint/invalid_nar_hash_prefix2511=== CONT TestGenerateFingerprint/invalid_nar_hash_length2512=== CONT TestGenerateFingerprint/invalid_reference_prefix2513=== CONT TestParseSigningKey/empty_name2514=== CONT TestParseSigningKey/valid_32-byte_key_with_different_name2515=== CONT TestGenerateFingerprint/unsorted_references_get_sorted2516--- PASS: TestGenerateFingerprint (0.00s)2517 --- PASS: TestGenerateFingerprint/basic_with_references (0.00s)2518 --- PASS: TestGenerateFingerprint/no_references (0.00s)2519 --- PASS: TestGenerateFingerprint/invalid_store_path_prefix (0.00s)2520 --- PASS: TestGenerateFingerprint/invalid_nar_hash_prefix (0.00s)2521 --- PASS: TestGenerateFingerprint/invalid_nar_hash_length (0.00s)2522 --- PASS: TestGenerateFingerprint/invalid_reference_prefix (0.00s)2523 --- PASS: TestGenerateFingerprint/unsorted_references_get_sorted (0.00s)2524=== CONT TestParseSigningKey/no_colon2525=== CONT TestExternalSignerErrors/missing_signature_name2526=== CONT TestParseSigningKey/wrong_length2527--- PASS: TestParseSigningKey (0.00s)2528 --- PASS: TestParseSigningKey/valid_32-byte_key (0.00s)2529 --- PASS: TestParseSigningKey/invalid_base64 (0.00s)2530 --- PASS: TestParseSigningKey/empty_name (0.00s)2531 --- PASS: TestParseSigningKey/valid_32-byte_key_with_different_name (0.00s)2532 --- PASS: TestParseSigningKey/no_colon (0.00s)2533 --- PASS: TestParseSigningKey/wrong_length (0.00s)2534=== CONT TestExternalSignerErrors/invalid_signature_base642535--- PASS: TestSignBatch (0.01s)2536=== CONT TestExternalSignerErrors/invalid_JSON2537=== CONT TestExternalSignerErrors/wrong_shape2538--- PASS: TestExternalSigner (0.23s)2539=== CONT TestExternalSignerErrors/exit_on_request2540--- PASS: TestExternalSignerProgramErrorKeepsProcess (0.40s)2541=== CONT TestExternalSignerErrors/wrong_signature_length2542--- PASS: TestExternalSignerClose (0.59s)2543=== CONT TestExternalSignerErrors/wrong_signature_name2544--- PASS: TestExternalSignerCancellation (0.80s)2545=== CONT TestExternalSignerErrors/too_few_signatures2546--- PASS: TestExternalSignerRestartsAfterCrash (0.98s)2547=== CONT TestExternalSignerErrors/exit_at_startup2548/nix/var/nix/builds/nix-38141-2789532961/TestExternalSignerRestartsAfterCrash682515248/001/sign program: line 7: /nix/var/nix/builds/nix-38141-2789532961/TestExternalSignerRestartsAfterCrash682515248/001/sign program.starts: No such file or directory2549--- PASS: TestExternalSignerWrongPublicKey (1.15s)2550=== CONT TestExternalSignerErrors/unrequested_key2551=== CONT TestExternalSignerErrors/wrong_signatures_shape2552=== CONT TestExternalSignerErrors/null_signatures2553=== CONT TestExternalSignerErrors/missing_signatures2554=== CONT TestExternalSignerErrors/wrong_id2555=== CONT TestExternalSignerErrors/null2556=== CONT TestExternalSignerErrors/program_error2557=== CONT TestSignErrors/nil_key2558=== CONT TestSignErrors/invalid_narinfo2559=== CONT TestSignErrors/canceled2560=== CONT TestSignErrors/nil_narinfo2561--- PASS: TestSignErrors (0.00s)2562 --- PASS: TestSignErrors/nil_key (0.00s)2563 --- PASS: TestSignErrors/invalid_narinfo (0.00s)2564 --- PASS: TestSignErrors/canceled (0.00s)2565 --- PASS: TestSignErrors/nil_narinfo (0.00s)2566--- PASS: TestExternalSignerErrors (0.00s)2567 --- PASS: TestExternalSignerErrors/invalid_signature_base64 (1.33s)2568 --- PASS: TestExternalSignerErrors/wrong_shape (1.52s)2569 --- PASS: TestExternalSignerErrors/invalid_JSON (1.71s)2570 --- PASS: TestExternalSignerErrors/missing_signature_name (1.90s)2571 --- PASS: TestExternalSignerErrors/exit_on_request (1.86s)2572 --- PASS: TestExternalSignerErrors/wrong_signature_length (1.88s)2573 --- PASS: TestExternalSignerErrors/wrong_signature_name (1.87s)2574 --- PASS: TestExternalSignerErrors/too_few_signatures (1.85s)2575 --- PASS: TestExternalSignerErrors/exit_at_startup (1.85s)2576 --- PASS: TestExternalSignerErrors/unrequested_key (1.88s)2577 --- PASS: TestExternalSignerErrors/wrong_signatures_shape (1.94s)2578 --- PASS: TestExternalSignerErrors/null_signatures (1.89s)2579 --- PASS: TestExternalSignerErrors/missing_signatures (1.89s)2580 --- PASS: TestExternalSignerErrors/wrong_id (1.89s)2581 --- PASS: TestExternalSignerErrors/null (1.90s)2582 --- PASS: TestExternalSignerErrors/program_error (1.90s)2583PASS2584Running OpenBao signer tests...2585=== RUN TestSign2586=== PAUSE TestSign2587=== RUN TestSignErrors2588=== PAUSE TestSignErrors2589=== RUN TestServe2590=== PAUSE TestServe2591=== RUN TestServeRejectsGarbage2592=== PAUSE TestServeRejectsGarbage2593=== CONT TestSign2594=== CONT TestServeRejectsGarbage2595=== CONT TestServe2596--- PASS: TestServeRejectsGarbage (0.00s)2597=== CONT TestSignErrors2598=== RUN TestSignErrors/http_error2599=== PAUSE TestSignErrors/http_error2600=== RUN TestSignErrors/invalid_json2601=== PAUSE TestSignErrors/invalid_json2602=== RUN TestSignErrors/no_prefix2603=== PAUSE TestSignErrors/no_prefix2604=== RUN TestSignErrors/invalid_base642605=== PAUSE TestSignErrors/invalid_base642606=== CONT TestSignErrors/http_error2607=== CONT TestSignErrors/no_prefix2608=== CONT TestSignErrors/invalid_json2609=== CONT TestSignErrors/invalid_base642610--- PASS: TestServe (0.00s)2611--- PASS: TestSign (0.00s)2612--- PASS: TestSignErrors (0.00s)2613 --- PASS: TestSignErrors/http_error (0.00s)2614 --- PASS: TestSignErrors/invalid_json (0.00s)2615 --- PASS: TestSignErrors/no_prefix (0.00s)2616 --- PASS: TestSignErrors/invalid_base64 (0.00s)2617PASS