niks3-go-unit-tests
checks.x86_64-linux.go-unit-tests
· build #370
· raw
1tribuchet: building on jamie2Running client tests...3=== RUN TestDoServerRequestAttachesToken4=== PAUSE TestDoServerRequestAttachesToken5=== RUN TestRegisterUploadedObjectReusesConnections6=== PAUSE TestRegisterUploadedObjectReusesConnections7=== RUN TestRunGarbageCollection_FinishedOnAnotherReplica8=== PAUSE TestRunGarbageCollection_FinishedOnAnotherReplica9=== RUN TestRunGarbageCollection_NotFoundAfterLocalRun10=== PAUSE TestRunGarbageCollection_NotFoundAfterLocalRun11=== RUN TestCaseHackSuffix12=== PAUSE TestCaseHackSuffix13=== RUN TestFilterOversizedClosures14=== PAUSE TestFilterOversizedClosures15=== RUN TestUploadMultipart_PartsInParallel16=== PAUSE TestUploadMultipart_PartsInParallel17=== RUN TestPartSizeForNAR18=== PAUSE TestPartSizeForNAR19=== RUN TestUploadMultipart_SupersededByPeer20=== PAUSE TestUploadMultipart_SupersededByPeer21=== RUN TestDumpPathCaseHackMatchesNix22--- PASS: TestDumpPathCaseHackMatchesNix (0.03s)23=== RUN TestDumpPathCaseHackCollision24--- PASS: TestDumpPathCaseHackCollision (0.00s)25=== RUN TestDumpPathMatchesNix26=== PAUSE TestDumpPathMatchesNix27=== RUN TestDumpPathSingleFile28=== PAUSE TestDumpPathSingleFile29=== RUN TestDumpPathWriterError30=== PAUSE TestDumpPathWriterError31=== RUN TestEncodeNixBase3232=== PAUSE TestEncodeNixBase3233=== RUN TestEncodeNixBase32WithRealHash34=== PAUSE TestEncodeNixBase32WithRealHash35=== RUN TestConvertHashToNix3236=== PAUSE TestConvertHashToNix3237=== RUN TestGetStorePathHash38=== PAUSE TestGetStorePathHash39=== RUN TestPathInfoHashCompatibility40=== PAUSE TestPathInfoHashCompatibility41=== RUN TestParsePathInfoJSON42=== PAUSE TestParsePathInfoJSON43=== RUN TestParsePathInfoJSONMultiplePaths44=== PAUSE TestParsePathInfoJSONMultiplePaths45=== RUN TestPathInfoCACompatibility46=== PAUSE TestPathInfoCACompatibility47=== RUN TestRateLimiterFeedback48=== PAUSE TestRateLimiterFeedback49=== RUN TestRateLimiterFeedback_400DoesNotCountAsSuccess50=== PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess51=== RUN TestResolveStorePath52=== PAUSE TestResolveStorePath53=== RUN TestDoWithRetry_BodyReplayedViaGetBody54=== PAUSE TestDoWithRetry_BodyReplayedViaGetBody55=== RUN TestShellSplit56=== PAUSE TestShellSplit57=== RUN TestShellSplitErrors58=== PAUSE TestShellSplitErrors59=== RUN TestStreamPushReportsEveryPath60=== PAUSE TestStreamPushReportsEveryPath61=== RUN TestStreamPushBatchesUnderLoad62=== PAUSE TestStreamPushBatchesUnderLoad63=== RUN TestStreamPushIsolatesFailures64=== PAUSE TestStreamPushIsolatesFailures65=== RUN TestStreamPushGivesUpOnDeadServer66=== PAUSE TestStreamPushGivesUpOnDeadServer67=== RUN TestStreamPushRequestLine68=== PAUSE TestStreamPushRequestLine69=== RUN TestStreamPushReportsSignatures70=== PAUSE TestStreamPushReportsSignatures71=== RUN TestClientSignaturesByStorePath72=== PAUSE TestClientSignaturesByStorePath73=== RUN TestSetClientTLS74=== PAUSE TestSetClientTLS75=== RUN TestSetClientTLSDoesNotMutateDefaultTransport76=== PAUSE TestSetClientTLSDoesNotMutateDefaultTransport77=== RUN TestSetClientTLSErrors78=== PAUSE TestSetClientTLSErrors79=== RUN TestStaticToken80=== PAUSE TestStaticToken81=== RUN TestFileTokenReadsAndCaches82=== PAUSE TestFileTokenReadsAndCaches83=== RUN TestFileTokenMissing84=== PAUSE TestFileTokenMissing85=== RUN TestFileTokenEmpty86=== PAUSE TestFileTokenEmpty87=== RUN TestScriptTokenNoExpiryRerunsEveryCall88=== PAUSE TestScriptTokenNoExpiryRerunsEveryCall89=== RUN TestScriptTokenCachesUntilRefresh90=== PAUSE TestScriptTokenCachesUntilRefresh91=== RUN TestScriptTokenEmptyToken92=== PAUSE TestScriptTokenEmptyToken93=== RUN TestScriptTokenBadJSON94=== PAUSE TestScriptTokenBadJSON95=== RUN TestScriptTokenScriptFails96=== PAUSE TestScriptTokenScriptFails97=== RUN TestScriptTokenEmptyCommand98=== PAUSE TestScriptTokenEmptyCommand99=== CONT TestDoServerRequestAttachesToken100=== CONT TestFileTokenReadsAndCaches101=== CONT TestRateLimiterFeedback102=== RUN TestRateLimiterFeedback/429_enables_limiter103=== CONT TestPathInfoCACompatibility104=== RUN TestPathInfoCACompatibility/null_ca_field105=== PAUSE TestPathInfoCACompatibility/null_ca_field106=== RUN TestPathInfoCACompatibility/old_string_format_-_text107=== PAUSE TestPathInfoCACompatibility/old_string_format_-_text108=== RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive109=== PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive110=== RUN TestPathInfoCACompatibility/new_structured_format_-_text111=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_text112=== RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method113=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method114=== CONT TestStaticToken115--- PASS: TestStaticToken (0.00s)116=== 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--- PASS: TestFileTokenReadsAndCaches (0.00s)126=== CONT TestScriptTokenBadJSON127=== CONT TestConvertHashToNix32128=== RUN TestConvertHashToNix32/SRI_format_to_Nix32129=== PAUSE TestConvertHashToNix32/SRI_format_to_Nix32130=== RUN TestConvertHashToNix32/already_Nix32_format131=== PAUSE TestConvertHashToNix32/already_Nix32_format132=== RUN TestConvertHashToNix32/invalid_format133=== PAUSE TestConvertHashToNix32/invalid_format134=== CONT TestEncodeNixBase32WithRealHash135--- PASS: TestEncodeNixBase32WithRealHash (0.00s)136=== CONT TestParsePathInfoJSON137=== RUN TestParsePathInfoJSON/Nix_format138=== PAUSE TestParsePathInfoJSON/Nix_format139=== CONT TestSetClientTLSDoesNotMutateDefaultTransport140=== CONT TestSetClientTLS141=== CONT TestClientSignaturesByStorePath142--- PASS: TestClientSignaturesByStorePath (0.00s)143=== CONT TestEncodeNixBase32144=== RUN TestEncodeNixBase32/test_string_hash145=== CONT TestStreamPushReportsSignatures146=== CONT TestStreamPushRequestLine147=== CONT TestStreamPushGivesUpOnDeadServer148=== CONT TestStreamPushIsolatesFailures149=== CONT TestStreamPushBatchesUnderLoad150=== CONT TestStreamPushReportsEveryPath151=== CONT TestShellSplitErrors152--- PASS: TestShellSplitErrors (0.00s)153=== CONT TestPathInfoHashCompatibility154=== RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)155=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)156=== RUN TestPathInfoHashCompatibility/old_string_format_with_colon157=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon158=== CONT TestShellSplit159=== CONT TestDoWithRetry_BodyReplayedViaGetBody1602026/10/06 10:46:33 ERROR Upload failed error="connection refused" count=20161=== RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI162=== CONT TestScriptTokenEmptyToken163=== CONT TestResolveStorePath164=== CONT TestScriptTokenEmptyCommand165=== CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess166=== CONT TestScriptTokenScriptFails1672026/10/06 10:46:33 ERROR Server seems unavailable, giving up on batch untried=17168=== CONT TestDumpPathSingleFile169=== PAUSE TestRateLimiterFeedback/429_enables_limiter170=== CONT TestSetClientTLSErrors171=== CONT TestParsePathInfoJSONMultiplePaths172=== RUN TestParsePathInfoJSON/Lix_format173=== PAUSE TestEncodeNixBase32/test_string_hash174=== CONT TestDumpPathWriterError1752026/10/06 10:46:33 WARN Rate limiter enabled after throttle name=server-test rate=5176=== PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI177=== RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512178=== PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512179=== CONT TestScriptTokenCachesUntilRefresh180--- PASS: TestScriptTokenEmptyCommand (0.00s)181=== CONT TestRunGarbageCollection_NotFoundAfterLocalRun1822026/10/06 10:46:33 ERROR Upload failed error="bad path" count=3183--- PASS: TestShellSplit (0.00s)184=== CONT TestFilterOversizedClosures185=== RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths186=== RUN TestFilterOversizedClosures/no_limit_keeps_everything187=== PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths188=== RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths189=== PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths190--- PASS: TestScriptTokenBadJSON (0.00s)191=== PAUSE TestFilterOversizedClosures/no_limit_keeps_everything192=== PAUSE TestParsePathInfoJSON/Lix_format193--- PASS: TestResolveStorePath (0.00s)194=== CONT TestDumpPathMatchesNix1952026/10/06 10:46:33 ERROR Upload failed error=boom count=1196=== RUN TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped197--- PASS: TestStreamPushGivesUpOnDeadServer (0.00s)198=== PAUSE TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped199=== RUN TestEncodeNixBase32/empty_input200=== CONT TestCaseHackSuffix201=== RUN TestRateLimiterFeedback/503_enables_limiter202=== RUN TestParsePathInfoJSON/empty_input203=== CONT TestFileTokenEmpty204=== RUN TestFilterOversizedClosures/all_closures_skipped205--- PASS: TestFileTokenEmpty (0.00s)206=== CONT TestUploadMultipart_SupersededByPeer207=== RUN TestUploadMultipart_SupersededByPeer/exists208=== PAUSE TestUploadMultipart_SupersededByPeer/exists209=== RUN TestUploadMultipart_SupersededByPeer/missing210=== PAUSE TestUploadMultipart_SupersededByPeer/missing211=== CONT TestPartSizeForNAR212=== RUN TestPartSizeForNAR/zero_stays_at_minimum213=== RUN TestSetClientTLSErrors/missing_cert_file214=== PAUSE TestFilterOversizedClosures/all_closures_skipped215=== PAUSE TestRateLimiterFeedback/503_enables_limiter216=== PAUSE TestEncodeNixBase32/empty_input217=== PAUSE TestParsePathInfoJSON/empty_input218=== CONT TestRunGarbageCollection_FinishedOnAnotherReplica219=== CONT TestUploadMultipart_PartsInParallel220=== PAUSE TestSetClientTLSErrors/missing_cert_file221=== CONT TestFileTokenMissing222=== RUN TestSetClientTLSErrors/missing_key_file2232026/10/06 10:46:33 ERROR Upload failed error=boom count=1224=== PAUSE TestSetClientTLSErrors/missing_key_file225=== CONT TestGetStorePathHash/valid_store_path2262026/10/06 10:46:33 WARN Rate limiter enabled after throttle name=server-test rate=5227=== RUN TestSetClientTLSErrors/missing_ca_file2282026/10/06 10:46:33 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:43655229=== RUN TestRateLimiterFeedback/200_does_not_enable_limiter230=== CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method231=== CONT TestPathInfoCACompatibility/new_structured_format_-_text232--- PASS: TestScriptTokenScriptFails (0.01s)233--- PASS: TestStreamPushReportsEveryPath (0.01s)2342026/10/06 10:46:33 WARN Rate limiter backed off name=server-test rate=52352026/10/06 10:46:33 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:43655236=== CONT TestRegisterUploadedObjectReusesConnections237=== CONT TestScriptTokenNoExpiryRerunsEveryCall238=== CONT TestPathInfoCACompatibility/null_ca_field239=== RUN TestParsePathInfoJSON/whitespace_only240=== PAUSE TestSetClientTLSErrors/missing_ca_file241=== PAUSE TestPartSizeForNAR/zero_stays_at_minimum242--- PASS: TestScriptTokenEmptyToken (0.01s)243=== PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter244=== CONT TestGetStorePathHash/hash_with_wrong_length_should_error245=== CONT TestGetStorePathHash/basename_without_hyphen_should_error246=== CONT TestConvertHashToNix32/invalid_format2472026/10/06 10:46:33 INFO Garbage collection started248=== CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)2492026/10/06 10:46:33 INFO Garbage collection started250=== CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI251=== CONT TestConvertHashToNix32/already_Nix32_format252=== CONT TestPathInfoHashCompatibility/old_string_format_with_colon253=== CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths254=== CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths255=== CONT TestUploadMultipart_SupersededByPeer/exists256=== CONT TestUploadMultipart_SupersededByPeer/missing257=== CONT TestPathInfoCACompatibility/old_string_format_-_text258=== CONT TestFilterOversizedClosures/all_closures_skipped259=== CONT TestFilterOversizedClosures/no_limit_keeps_everything2602026/10/06 10:46:33 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=50261=== CONT TestEncodeNixBase32/test_string_hash262=== CONT TestEncodeNixBase32/empty_input263=== RUN TestSetClientTLS/rejects_connection_without_client_cert264=== RUN TestSetClientTLSErrors/invalid_ca_file265=== PAUSE TestSetClientTLSErrors/invalid_ca_file266=== CONT TestSetClientTLSErrors/missing_cert_file267=== CONT TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped268=== RUN TestRateLimiterFeedback/400_does_not_enable_limiter269=== CONT TestSetClientTLSErrors/invalid_ca_file2702026/10/06 10:46:33 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=2000271=== CONT TestSetClientTLSErrors/missing_key_file272=== CONT TestGetStorePathHash/hash_with_invalid_characters_should_error273=== PAUSE TestParsePathInfoJSON/whitespace_only274=== RUN TestParsePathInfoJSON/invalid_JSON275=== PAUSE TestParsePathInfoJSON/invalid_JSON276=== CONT TestParsePathInfoJSON/Nix_format277=== CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive278--- PASS: TestDoServerRequestAttachesToken (0.02s)2792026/10/06 10:46:33 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=0280=== CONT TestParsePathInfoJSON/invalid_JSON281=== CONT TestParsePathInfoJSON/whitespace_only282=== CONT TestParsePathInfoJSON/empty_input283=== CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512284=== PAUSE TestSetClientTLS/rejects_connection_without_client_cert285=== RUN TestSetClientTLS/succeeds_with_client_cert_and_CA286=== CONT TestSetClientTLSErrors/missing_ca_file287=== RUN TestPartSizeForNAR/small_stays_at_minimum288=== PAUSE TestPartSizeForNAR/small_stays_at_minimum289=== RUN TestPartSizeForNAR/80_GiB_fits_at_minimum290=== PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum291=== RUN TestPartSizeForNAR/115_GiB_needs_larger_parts292=== PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts293=== RUN TestPartSizeForNAR/1_TiB294=== PAUSE TestPartSizeForNAR/1_TiB295=== RUN TestPartSizeForNAR/5_TiB_S3_max_object296=== PAUSE TestPartSizeForNAR/5_TiB_S3_max_object297=== RUN TestPartSizeForNAR/capped_at_5_GiB298=== PAUSE TestPartSizeForNAR/capped_at_5_GiB299=== CONT TestPartSizeForNAR/zero_stays_at_minimum300=== PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter301=== CONT TestRateLimiterFeedback/429_enables_limiter302=== CONT TestPartSizeForNAR/80_GiB_fits_at_minimum303=== CONT TestPartSizeForNAR/capped_at_5_GiB304=== CONT TestPartSizeForNAR/5_TiB_S3_max_object305=== CONT TestPartSizeForNAR/1_TiB306=== CONT TestRateLimiterFeedback/400_does_not_enable_limiter307=== CONT TestRateLimiterFeedback/503_enables_limiter308=== CONT TestRateLimiterFeedback/200_does_not_enable_limiter309=== CONT TestPartSizeForNAR/115_GiB_needs_larger_parts310--- PASS: TestStreamPushReportsSignatures (0.02s)311=== CONT TestParsePathInfoJSON/Lix_format3122026/10/06 10:46:33 WARN Garbage collection finished on another replica. Its statistics are not available here313--- PASS: TestStreamPushIsolatesFailures (0.02s)314--- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.02s)315=== PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA316=== CONT TestPartSizeForNAR/small_stays_at_minimum317=== CONT TestConvertHashToNix32/SRI_format_to_Nix323182026/10/06 10:46:33 WARN Rate limiter enabled after throttle name=server-test rate=5319--- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.05s)3202026/10/06 10:46:33 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:33929321=== RUN TestSetClientTLS/preserves_debug_logging_transport322=== PAUSE TestSetClientTLS/preserves_debug_logging_transport323=== CONT TestSetClientTLS/rejects_connection_without_client_cert3242026/10/06 10:46:33 WARN Rate limiter enabled after throttle name=server-test rate=5325=== CONT TestSetClientTLS/preserves_debug_logging_transport3262026/10/06 10:46:33 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:441713272026/10/06 10:46:33 WARN Rate limiter backed off name=server-test rate=5328=== CONT TestSetClientTLS/succeeds_with_client_cert_and_CA3292026/10/06 10:46:33 WARN Rate limiter backed off name=server-test rate=5330--- PASS: TestFileTokenMissing (0.03s)331--- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)332 --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)333 --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)334--- PASS: TestDumpPathSingleFile (0.05s)335--- PASS: TestScriptTokenCachesUntilRefresh (0.05s)336--- PASS: TestRunGarbageCollection_NotFoundAfterLocalRun (0.05s)337--- PASS: TestRunGarbageCollection_FinishedOnAnotherReplica (0.04s)338--- PASS: TestPathInfoHashCompatibility (0.00s)339 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)340 --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)341 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)342 --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)343--- PASS: TestConvertHashToNix32 (0.00s)344 --- PASS: TestConvertHashToNix32/invalid_format (0.00s)345 --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)346 --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)347--- PASS: TestFilterOversizedClosures (0.00s)348 --- PASS: TestFilterOversizedClosures/no_limit_keeps_everything (0.00s)349 --- PASS: TestFilterOversizedClosures/all_closures_skipped (0.00s)350 --- PASS: TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped (0.00s)351--- PASS: TestEncodeNixBase32 (0.01s)352 --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)353 --- PASS: TestEncodeNixBase32/empty_input (0.00s)354--- PASS: TestGetStorePathHash (0.00s)355 --- PASS: TestGetStorePathHash/valid_store_path (0.00s)356 --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)357 --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)358 --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)359--- PASS: TestUploadMultipart_SupersededByPeer (0.00s)360 --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)361 --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)362--- PASS: TestPartSizeForNAR (0.05s)363 --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)364 --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)365 --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)366 --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)367 --- PASS: TestPartSizeForNAR/1_TiB (0.00s)368 --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)369 --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)370--- PASS: TestRateLimiterFeedback (0.05s)371 --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)372 --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)373 --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)374 --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)375--- PASS: TestParsePathInfoJSON (0.05s)376 --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)377 --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)378 --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)379 --- PASS: TestParsePathInfoJSON/empty_input (0.00s)380 --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)381--- PASS: TestPathInfoCACompatibility (0.00s)382 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)383 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.03s)384 --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)385 --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)386 --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)387--- PASS: TestSetClientTLSErrors (0.05s)388 --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)389 --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)390 --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)391 --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)392--- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.02s)3932026/10/06 10:46:33 http: TLS handshake error from 127.0.0.1:38850: remote error: tls: bad certificate394--- PASS: TestSetClientTLS (0.05s)395 --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)396 --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)397 --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.02s)398--- PASS: TestStreamPushRequestLine (0.07s)399--- PASS: TestCaseHackSuffix (0.08s)400--- PASS: TestRegisterUploadedObjectReusesConnections (0.04s)401--- PASS: TestDumpPathWriterError (0.09s)402--- PASS: TestStreamPushBatchesUnderLoad (0.10s)403--- PASS: TestDumpPathMatchesNix (0.13s)404--- PASS: TestUploadMultipart_PartsInParallel (0.66s)405--- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)406PASS407Running server tests...408The files belonging to this database system will be owned by user "nixbld".409This user must also own the server process.410411The database cluster will be initialized with locale "C".412The default database encoding has accordingly been set to "SQL_ASCII".413The default text search configuration will be set to "english".414415Data page checksums are enabled.416417creating directory /build/postgres2348635956/data ... ok418creating subdirectories ... ok419selecting dynamic shared memory implementation ... posix420selecting default "max_connections" ... 100421selecting default "shared_buffers" ... 128MB422selecting default time zone ... UTC423creating configuration files ... ok424running bootstrap script ... ok425performing post-bootstrap initialization ... ok426syncing data to disk ... ok427428initdb: warning: enabling "trust" authentication for local connections429initdb: 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.430431Success. You can now start the database server using:432433 pg_ctl -D /build/postgres2348635956/data -l logfile start434435/build/postgres2348635956:5432 - no response4362026-10-06 10:46:35.034 UTC [128] LOG: starting PostgreSQL 18.6 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit4372026-10-06 10:46:35.035 UTC [128] LOG: listening on Unix socket "/build/postgres2348635956/.s.PGSQL.5432"4382026-10-06 10:46:35.039 UTC [135] LOG: database system was shut down at 2026-10-06 10:46:34 UTC4392026-10-06 10:46:35.044 UTC [128] LOG: database system is ready to accept connections440/build/postgres2348635956:5432 - accepting connections441{"timestamp":"2026-10-06T10:46:35.340252407Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"4ff82fb8-b1ec-498d-9c3f-f038ff5270a6","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(391)"}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 10:46:35 INFO lead: acquired remote=192.0.2.1:12344882026/10/06 10:46:36 INFO lead: released remote=192.0.2.1:12344892026/10/06 10:46:36 INFO lead: acquired remote=192.0.2.1:12344902026/10/06 10:46:37 INFO lead: released remote=192.0.2.1:1234491--- PASS: TestLeadIncumbentWinsAfterRestart (1.90s)492=== RUN TestLeadIncumbentAnnouncesAtOnce493=== PAUSE TestLeadIncumbentAnnouncesAtOnce494=== RUN TestLeadEndsOnShutdown495=== PAUSE TestLeadEndsOnShutdown496=== RUN TestLeadEndsWhenItsConnectionHangs4972026/10/06 10:46:37 INFO lead: acquired remote=192.0.2.1:12344982026-10-06 10:46:38.858 UTC [580] FATAL: terminating connection due to administrator command4992026/10/06 10:46:39 INFO lead: acquired remote=192.0.2.1:12345002026/10/06 10:46:40 WARN lead: lock connection lost error="timeout: context deadline exceeded"5012026/10/06 10:46:40 INFO lead: released remote=192.0.2.1:12345022026/10/06 10:46:40 INFO lead: released remote=192.0.2.1:1234503--- PASS: TestLeadEndsWhenItsConnectionHangs (3.11s)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 10:46:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6602026/10/06 10:46:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6612026/10/06 10:46:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6622026/10/06 10:46:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6632026/10/06 10:46:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6642026/10/06 10:46:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6652026/10/06 10:46:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6662026/10/06 10:46:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6672026/10/06 10:46:40 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6682026/10/06 10:46:40 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 TestService_AuthMiddleware693=== CONT TestSkippedUploadsHandler694=== CONT TestParseSingleRange695=== RUN TestParseSingleRange/none696=== PAUSE TestParseSingleRange/none697=== RUN TestParseSingleRange/unknown_unit698=== PAUSE TestParseSingleRange/unknown_unit699=== RUN TestParseSingleRange/multi-range_ignored700=== PAUSE TestParseSingleRange/multi-range_ignored701=== RUN TestParseSingleRange/malformed_no_dash702=== CONT TestReadRedirectKeepsNarinfoProxied7032026/10/06 10:46:40 INFO Client skipped oversized paths paths=3 nar_bytes=5000000000704=== CONT TestCreatePendingClosure_SmallNARUsesSimplePUT705=== CONT TestCompleteMultipartUnregistered706=== CONT TestService_verifyS3Integrity707=== CONT TestService_createPendingClosureHandler708=== CONT TestService_cleanupPendingClosuresHandler709=== CONT TestUploadHandlersRejectOversizedBody710=== CONT TestUploadHandlersRejectInvalidKeys711=== CONT TestIsValidUploadKey712=== CONT TestPendingClosureWriteTimeout713=== RUN TestPendingClosureWriteTimeout/empty714=== PAUSE TestPendingClosureWriteTimeout/empty715=== CONT TestProxyWriteTimeout716=== CONT TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle717=== CONT TestParseSize718--- PASS: TestParseSize (0.00s)719=== CONT TestPush_CompleteCommitsEveryRoot720=== CONT TestService_Rustfstest721=== CONT TestPresignedUploadRegisteredBeforeCommit722=== CONT TestCompletedNarNotReofferedAcrossClosures723=== CONT TestCompleteMultipartUpload_ErrorButObjectExists724=== CONT TestRedundantMultipartUpload725=== CONT TestPush_SignsNarinfosOfItsPendingObjects726=== CONT TestPush_RejectsBadRequests727=== CONT TestPush_SkippedKeySurvivesGCBeforeCommit728--- PASS: TestSkippedUploadsHandler (0.09s)729=== CONT TestPush_OverlappingRootsStoreOneRowPerKey730=== PAUSE TestParseSingleRange/malformed_no_dash731=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info732=== RUN TestPendingClosureWriteTimeout/400_objects733=== RUN TestIsValidUploadKey/narinfo734=== RUN TestProxyWriteTimeout/narinfo735=== PAUSE TestProxyWriteTimeout/narinfo736=== RUN TestProxyWriteTimeout/1_GiB_nar737=== PAUSE TestProxyWriteTimeout/1_GiB_nar738=== RUN TestProxyWriteTimeout/10_GiB_nar739=== PAUSE TestProxyWriteTimeout/10_GiB_nar740=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info741=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal742=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal743=== RUN TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key744=== PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key745=== PAUSE TestPendingClosureWriteTimeout/400_objects746=== RUN TestProxyWriteTimeout/unknown_size747=== PAUSE TestProxyWriteTimeout/unknown_size748=== PAUSE TestIsValidUploadKey/narinfo749=== RUN TestIsValidUploadKey/nar_zst750=== PAUSE TestIsValidUploadKey/nar_zst751=== RUN TestIsValidUploadKey/nar_xz752=== PAUSE TestIsValidUploadKey/nar_xz753=== RUN TestIsValidUploadKey/nar_plain754=== PAUSE TestIsValidUploadKey/nar_plain755=== RUN TestParseSingleRange/malformed_both_empty756=== PAUSE TestParseSingleRange/malformed_both_empty757=== RUN TestParseSingleRange/malformed_end_before_start758=== PAUSE TestParseSingleRange/malformed_end_before_start759=== RUN TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key760=== PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key761=== RUN TestPendingClosureWriteTimeout/670k_objects762=== PAUSE TestPendingClosureWriteTimeout/670k_objects763=== CONT TestReadRedirectUsesPublicS3URL764=== RUN TestIsValidUploadKey/listing765=== PAUSE TestIsValidUploadKey/listing766=== RUN TestIsValidUploadKey/build_log767=== PAUSE TestIsValidUploadKey/build_log768=== RUN TestIsValidUploadKey/build_log_home-manager_file769=== PAUSE TestIsValidUploadKey/build_log_home-manager_file770=== RUN TestParseSingleRange/closed771=== RUN TestIsValidUploadKey/build_log_plus_in_name772=== PAUSE TestIsValidUploadKey/build_log_plus_in_name773=== RUN TestIsValidUploadKey/build_log_question_mark774=== PAUSE TestParseSingleRange/closed775=== CONT TestReadProxyRangeRequest776=== RUN TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline777=== PAUSE TestIsValidUploadKey/build_log_question_mark778=== RUN TestParseSingleRange/open-ended779=== PAUSE TestParseSingleRange/open-ended780=== RUN TestParseSingleRange/end_clamped_to_size781=== PAUSE TestParseSingleRange/end_clamped_to_size782=== RUN TestParseSingleRange/suffix783=== PAUSE TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline784=== RUN TestIsValidUploadKey/build_log_equals785=== CONT TestSweepSkipsRowsLockedByPush786=== PAUSE TestIsValidUploadKey/build_log_equals787=== RUN TestIsValidUploadKey/realisation788=== PAUSE TestIsValidUploadKey/realisation789=== PAUSE TestParseSingleRange/suffix790=== RUN TestIsValidUploadKey/realisation_plus_in_output791=== PAUSE TestIsValidUploadKey/realisation_plus_in_output792=== RUN TestIsValidUploadKey/nix-cache-info793=== PAUSE TestIsValidUploadKey/nix-cache-info794=== RUN TestIsValidUploadKey/index.html795=== PAUSE TestIsValidUploadKey/index.html796=== RUN TestIsValidUploadKey/narinfo_key,_nar_type797=== RUN TestParseSingleRange/suffix_exceeds_size798=== PAUSE TestIsValidUploadKey/narinfo_key,_nar_type799=== RUN TestIsValidUploadKey/nar_key,_narinfo_type800=== PAUSE TestParseSingleRange/suffix_exceeds_size801=== RUN TestParseSingleRange/single_byte802=== PAUSE TestParseSingleRange/single_byte803=== RUN TestParseSingleRange/start_past_EOF804=== PAUSE TestParseSingleRange/start_past_EOF805=== RUN TestParseSingleRange/start_far_past_EOF806=== PAUSE TestParseSingleRange/start_far_past_EOF807=== PAUSE TestIsValidUploadKey/nar_key,_narinfo_type808=== RUN TestIsValidUploadKey/listing_key,_narinfo_type809=== PAUSE TestIsValidUploadKey/listing_key,_narinfo_type810=== RUN TestIsValidUploadKey/traversal811=== CONT TestPresentNotReportedWhileGCDeletesClosure812=== PAUSE TestIsValidUploadKey/traversal813=== RUN TestIsValidUploadKey/traversal_nar814=== PAUSE TestIsValidUploadKey/traversal_nar815=== RUN TestIsValidUploadKey/absolute816=== PAUSE TestIsValidUploadKey/absolute817=== RUN TestIsValidUploadKey/empty_key818=== PAUSE TestIsValidUploadKey/empty_key819=== RUN TestIsValidUploadKey/unknown_type820=== PAUSE TestIsValidUploadKey/unknown_type821=== CONT TestPresentReportsOnlyClosureRoots822=== RUN TestUploadHandlersRejectOversizedBody/create_pending_closure823=== PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure824=== RUN TestUploadHandlersRejectOversizedBody/complete_multipart825=== PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart826=== RUN TestUploadHandlersRejectOversizedBody/request_more_parts827=== PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts828=== CONT TestCreatePin_ReservedPins8292026/10/06 10:46:40 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:36875/oidc8302026/10/06 10:46:41 INFO Received push request method=POST path=/api/pushes8312026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures8322026/10/06 10:46:41 INFO Received complete push request method=POST path=/api/pushes/1/complete833--- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.48s)834=== CONT TestOrphanedObjectsGCStressTest835--- PASS: TestPush_CompleteCommitsEveryRoot (0.48s)836=== CONT TestOrphanedObjectsGC837--- PASS: TestReadRedirectKeepsNarinfoProxied (0.50s)838=== CONT TestGCTaskStore_Fail839--- PASS: TestGCTaskStore_Fail (0.00s)840=== CONT TestGCTaskStore_PhaseUpdates841--- PASS: TestGCTaskStore_PhaseUpdates (0.00s)842=== CONT TestGCTaskStore_CompletedAllowsNewTask843--- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)844=== CONT TestGCTaskStore_GetReturnsLatest845--- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)846=== CONT TestGCTaskStore_GetEmpty847--- PASS: TestGCTaskStore_GetEmpty (0.00s)848=== CONT TestGCTaskStore_ConflictDifferentParams849--- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)850=== CONT TestGCTaskStore_DeduplicateSameParams851--- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)852=== CONT TestGCTaskStore_StartNew853--- PASS: TestGCTaskStore_StartNew (0.00s)854=== CONT TestGCEndsOnShutdown855=== RUN TestGCEndsOnShutdown/before_the_run856=== PAUSE TestGCEndsOnShutdown/before_the_run857=== RUN TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete858=== PAUSE TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete859=== RUN TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark860=== PAUSE TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark861=== CONT TestCommitRacingPendingCleanupKeepsObjects8622026/10/06 10:46:41 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"863--- PASS: TestService_AuthMiddleware (0.52s)864=== CONT TestGracefulShutdownDrainsInflight8652026/10/06 10:46:41 INFO Starting HTTP server address=127.0.0.1:436438662026/10/06 10:46:41 INFO Shutdown signal received, draining in-flight requests timeout=10s8672026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures8682026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures8692026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures8702026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures871--- PASS: TestGracefulShutdownDrainsInflight (0.07s)872=== CONT TestPushWaitsForSweepHoldingRow8732026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures8742026/10/06 10:46:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete8752026/10/06 10:46:41 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst876--- PASS: TestCompleteMultipartUnregistered (0.63s)877=== CONT TestObjectStatsTrigger878--- PASS: TestService_Rustfstest (0.66s)879=== CONT TestSweepRechecksPendingAfterLocking8802026/10/06 10:46:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete8812026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures8822026/10/06 10:46:41 INFO Registered completed upload object_key=nar/jjjjjjjjjjjjjjjjjjjjjjjjjjjjjj0100000000000000000000.nar.zst8832026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures884--- PASS: TestPresignedUploadRegisteredBeforeCommit (0.71s)885=== CONT TestPendingCleanupUsesOneCutoff8862026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures8872026/10/06 10:46:41 INFO Received cleanup request method=DELETE path=/api/pending_closures8882026/10/06 10:46:41 INFO Aborted multipart uploads count=0 kept=08892026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures8902026/10/06 10:46:41 INFO Received cleanup request method=DELETE path=/api/pending_closures8912026/10/06 10:46:41 INFO Aborted multipart uploads count=1 kept=08922026/10/06 10:46:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete8932026-10-06 10:46:41.461 UTC [665] ERROR: Closure does not exist: id=18942026-10-06 10:46:41.461 UTC [665] CONTEXT: PL/pgSQL function commit_pending_closure(bigint) line 12 at RAISE8952026-10-06 10:46:41.461 UTC [665] STATEMENT: -- name: CommitPendingClosure :exec896 SELECT commit_pending_closure($1::bigint)897 898--- PASS: TestService_cleanupPendingClosuresHandler (0.77s)899=== CONT TestGetExistingObjectsLocksRows9002026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures9012026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures9022026/10/06 10:46:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete9032026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures9042026/10/06 10:46:41 WARN CompleteMultipartUpload errored but object exists; treating as success error="The specified key does not exist." object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=M2ExZjdlNGItNzdjMC00YzYzLThjMDktYzM0YTQ1ZWQ0ZmI2LmMxNjEwZjdlLWFhMjAtNDc2Ny04ZmJhLWVmZTFmZjk3OTI3NHgxNzkxMjgzNjAxNDgzODY3MTA19052026/10/06 10:46:41 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=M2ExZjdlNGItNzdjMC00YzYzLThjMDktYzM0YTQ1ZWQ0ZmI2LmMxNjEwZjdlLWFhMjAtNDc2Ny04ZmJhLWVmZTFmZjk3OTI3NHgxNzkxMjgzNjAxNDgzODY3MTA1 parts=1906--- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.76s)907=== CONT TestMultipartUploadAbortedWhenCancelledBeforeRecorded908--- PASS: TestReadRedirectUsesPublicS3URL (0.77s)909=== CONT TestMultipartCleanup910--- PASS: TestReadProxyRangeRequest (0.79s)911=== CONT TestServerTLSConfig912=== RUN TestServerTLSConfig/no_client_CA913=== PAUSE TestServerTLSConfig/no_client_CA914=== RUN TestServerTLSConfig/missing_CA_file915=== PAUSE TestServerTLSConfig/missing_CA_file916=== RUN TestServerTLSConfig/not_a_PEM_file917=== PAUSE TestServerTLSConfig/not_a_PEM_file918=== CONT TestService_NativeMTLS9192026/10/06 10:46:41 INFO Received push request method=POST path=/api/pushes9202026/10/06 10:46:41 INFO Received complete push request method=POST path=/api/pushes/1/complete9212026/10/06 10:46:41 INFO Received push request method=POST path=/api/pushes9222026/10/06 10:46:41 INFO Aborted multipart uploads count=0 kept=09232026/10/06 10:46:41 WARN Force mode enabled - objects will be deleted immediately without grace period9242026/10/06 10:46:41 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=09252026/10/06 10:46:41 INFO Vacuumed table table=pending_closures9262026/10/06 10:46:41 INFO Vacuumed table table=pending_objects9272026/10/06 10:46:41 INFO Vacuumed table table=multipart_uploads9282026/10/06 10:46:41 INFO Vacuumed table table=closures9292026/10/06 10:46:41 INFO Vacuumed table table=objects9302026/10/06 10:46:41 INFO Aborted multipart uploads count=0 kept=09312026/10/06 10:46:41 WARN Force mode enabled - objects will be deleted immediately without grace period932--- PASS: TestPresentReportsOnlyClosureRoots (0.91s)933=== CONT TestRegisterCompletedObjectNeedsPendingRow9342026/10/06 10:46:41 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=09352026/10/06 10:46:41 INFO Vacuumed table table=pending_closures9362026/10/06 10:46:41 INFO Vacuumed table table=pending_objects9372026/10/06 10:46:41 INFO Vacuumed table table=multipart_uploads9382026/10/06 10:46:41 INFO Vacuumed table table=closures9392026/10/06 10:46:41 INFO Vacuumed table table=objects940--- PASS: TestSweepSkipsRowsLockedByPush (0.93s)941=== CONT TestMetricsInventory9422026/10/06 10:46:41 INFO Received push request method=POST path=/api/pushes9432026/10/06 10:46:41 INFO Aborted multipart uploads count=0 kept=09442026/10/06 10:46:41 WARN Force mode enabled - objects will be deleted immediately without grace period9452026/10/06 10:46:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete9462026/10/06 10:46:41 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=09472026/10/06 10:46:41 INFO Vacuumed table table=pending_closures948--- PASS: TestPush_OverlappingRootsStoreOneRowPerKey (0.96s)949=== CONT TestGenerateLandingPage9502026/10/06 10:46:41 INFO Vacuumed table table=pending_objects951--- PASS: TestGenerateLandingPage (0.01s)952=== CONT TestCacheConfigHandlerMaxNarSize953--- PASS: TestCacheConfigHandlerMaxNarSize (0.00s)954=== CONT TestService_readinessHandler9552026/10/06 10:46:41 INFO Vacuumed table table=multipart_uploads9562026/10/06 10:46:41 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=M2ExZjdlNGItNzdjMC00YzYzLThjMDktYzM0YTQ1ZWQ0ZmI2LmJhNTc0YjQ3LWY1YTAtNDNkNS1hNjYwLWUwYjJmMjY5NjI4OHgxNzkxMjgzNjAxMjUxMTI0MDE0 parts=109572026/10/06 10:46:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete9582026/10/06 10:46:41 INFO Vacuumed table table=closures9592026/10/06 10:46:41 INFO Vacuumed table table=objects9602026/10/06 10:46:41 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux9612026/10/06 10:46:41 WARN Refused reserved pin name=worker-x86_64-linux9622026/10/06 10:46:41 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux9632026/10/06 10:46:41 INFO Received create pin request method=POST path=/api/pins/my-app9642026/10/06 10:46:41 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux965--- PASS: TestCreatePin_ReservedPins (0.90s)9662026/10/06 10:46:41 INFO Received complete push request method=POST path=/api/pushes/2/complete967=== CONT TestNARDeduplicationMetadataUploadBug9682026/10/06 10:46:41 INFO Completed upload id=19692026/10/06 10:46:41 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000009702026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures971--- PASS: TestPush_SkippedKeySurvivesGCBeforeCommit (0.99s)972=== CONT TestService_healthCheckHandler973=== RUN TestPush_RejectsBadRequests/no_roots974=== PAUSE TestPush_RejectsBadRequests/no_roots975=== RUN TestPush_RejectsBadRequests/no_objects976=== PAUSE TestPush_RejectsBadRequests/no_objects977=== RUN TestPush_RejectsBadRequests/bad_root978=== PAUSE TestPush_RejectsBadRequests/bad_root979=== RUN TestPush_RejectsBadRequests/root_not_in_objects980=== PAUSE TestPush_RejectsBadRequests/root_not_in_objects981=== CONT TestCreatePendingClosureRejectsOversizedNAR9822026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures983--- PASS: TestCreatePendingClosureRejectsOversizedNAR (0.00s)984=== CONT TestReadProxyInvalidPath9852026/10/06 10:46:41 INFO Starting cleanup of old closures method=DELETE path=/api/closures9862026/10/06 10:46:41 INFO Aborted multipart uploads count=0 kept=09872026/10/06 10:46:41 INFO Received complete multipart upload request method=POST path=/api/multipart/complete9882026/10/06 10:46:41 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=09892026/10/06 10:46:41 INFO Vacuumed table table=pending_closures9902026/10/06 10:46:41 INFO Vacuumed table table=pending_objects9912026/10/06 10:46:41 INFO Received push request method=POST path=/api/pushes9922026/10/06 10:46:41 INFO Vacuumed table table=multipart_uploads9932026/10/06 10:46:41 INFO Vacuumed table table=closures9942026/10/06 10:46:41 INFO Vacuumed table table=objects9952026/10/06 10:46:41 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=M2ExZjdlNGItNzdjMC00YzYzLThjMDktYzM0YTQ1ZWQ0ZmI2LmI4YmViMmEzLTM4NzAtNDYyZi1hNTNmLTk1OTZjMWJlMmJjYngxNzkxMjgzNjAxMzAyMzI5OTc1 parts=109962026/10/06 10:46:41 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete9972026/10/06 10:46:41 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign9982026/10/06 10:46:41 INFO Completed upload id=19992026/10/06 10:46:41 INFO Received get closure request method=GET path=/api/closures/0000000000000000000000000000000010002026/10/06 10:46:41 INFO Signed narinfos id=1 count=110012026/10/06 10:46:41 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign10022026/10/06 10:46:41 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1003--- PASS: TestService_createPendingClosureHandler (1.13s)1004=== CONT TestConcurrentCommitsSharingObjectsDoNotDeadlock10052026/10/06 10:46:41 ERROR Failed to sign narinfos signer="index 1" error="signing key cannot be nil"10062026/10/06 10:46:41 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign10072026/10/06 10:46:41 ERROR Failed to sign narinfos signer=niks3-test-1 error="signer failed"10082026/10/06 10:46:41 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign10092026/10/06 10:46:41 ERROR Signer returned unexpected signature count signer=niks3-test-1 got=0 want=110102026/10/06 10:46:41 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign10112026/10/06 10:46:41 ERROR Signer returned unexpected object key signer=niks3-test-1 object_key=unexpected.narinfo1012--- PASS: TestPush_SignsNarinfosOfItsPendingObjects (1.05s)1013=== CONT TestReadRedirectNar10142026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures10152026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures10162026/10/06 10:46:41 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo10172026/10/06 10:46:41 WARN Found objects in DB but missing from S3, will re-upload count=11018--- PASS: TestService_verifyS3Integrity (1.16s)1019=== CONT TestReadProxyDisabled10202026/10/06 10:46:41 INFO Received uploads request method=POST path=/api/pending_closures10212026/10/06 10:46:41 INFO Aborted multipart uploads count=0 kept=010222026/10/06 10:46:41 WARN Force mode enabled - objects will be deleted immediately without grace period10232026/10/06 10:46:41 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=010242026/10/06 10:46:41 INFO Vacuumed table table=pending_closures10252026/10/06 10:46:41 INFO Vacuumed table table=pending_objects10262026/10/06 10:46:41 INFO Vacuumed table table=multipart_uploads10272026/10/06 10:46:41 INFO Vacuumed table table=closures10282026/10/06 10:46:41 INFO Vacuumed table table=objects1029--- PASS: TestCommitRacingPendingCleanupKeepsObjects (0.80s)1030=== CONT TestFailedS3DeleteKeepsTombstone1031--- PASS: TestObjectStatsTrigger (0.67s)1032=== CONT TestReadProxyRootRedirectsToIndexHTML10332026/10/06 10:46:42 INFO Received complete multipart upload request method=POST path=/api/multipart/complete10342026/10/06 10:46:42 INFO Aborted multipart uploads count=0 kept=010352026/10/06 10:46:42 WARN Force mode enabled - objects will be deleted immediately without grace period10362026/10/06 10:46:42 INFO Completed multipart upload object_key=nar/hhhhhhhhhhhhhhhhhhhhhhhhhhhhhh0100000000000000000000.nar.zst upload_id=M2ExZjdlNGItNzdjMC00YzYzLThjMDktYzM0YTQ1ZWQ0ZmI2Ljk5ZGRhNzExLTQwNDMtNDFjNC1iZDU0LWJiNWRiYjQ4YzdkZHgxNzkxMjgzNjAxNDIxODQwMDUw parts=1210372026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures10382026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures1039--- PASS: TestCompletedNarNotReofferedAcrossClosures (1.34s)1040=== CONT TestForceGCDuringPushOffersSweptObject1041=== RUN TestForceGCDuringPushOffersSweptObject/before_pending_rows1042=== PAUSE TestForceGCDuringPushOffersSweptObject/before_pending_rows1043=== RUN TestForceGCDuringPushOffersSweptObject/after_presence_check1044=== PAUSE TestForceGCDuringPushOffersSweptObject/after_presence_check1045=== CONT TestReadProxyConditionalGet10462026/10/06 10:46:42 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=010472026/10/06 10:46:42 INFO Vacuumed table table=pending_closures10482026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures10492026/10/06 10:46:42 INFO Vacuumed table table=pending_objects10502026/10/06 10:46:42 INFO Vacuumed table table=multipart_uploads10512026/10/06 10:46:42 INFO Vacuumed table table=closures10522026/10/06 10:46:42 INFO Vacuumed table table=objects1053--- PASS: TestSweepRechecksPendingAfterLocking (0.70s)1054=== CONT TestCreatePendingClosureVerifyS3FailureReleasesConnection10552026/10/06 10:46:42 INFO Received cleanup request method=DELETE path=/api/pending_closures10562026/10/06 10:46:42 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1057--- PASS: TestGetExistingObjectsLocksRows (0.63s)1058=== CONT TestReadProxyOutlastsServerWriteTimeout10592026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures1060--- PASS: TestPresentNotReportedWhileGCDeletesClosure (1.33s)1061=== CONT TestGCSweepDeliversEachKeyOnce10622026/10/06 10:46:42 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=M2ExZjdlNGItNzdjMC00YzYzLThjMDktYzM0YTQ1ZWQ0ZmI2LmU0MWJmNzU1LTM0ZmMtNDg4Ni05YzBlLTRhYTc0YmFmZjgyNXgxNzkxMjgzNjAxNTEyMzY0NTI1 parts=1210632026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures1064--- PASS: TestMultipartUploadAbortedWhenCancelledBeforeRecorded (0.58s)1065=== CONT TestTombstonedObjectOfferedWithoutWaiting10662026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures10672026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures10682026/10/06 10:46:42 WARN mTLS auth: subject not in bound subjects subject="CN=reader"10692026/10/06 10:46:42 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1070--- PASS: TestService_NativeMTLS (0.57s)1071=== CONT TestReadProxyHead1072=== NAME TestOrphanedObjectsGC1073 orphaned_objects_gc_test.go:290: GC Test Summary:1074 orphaned_objects_gc_test.go:291: - Kept: 2 objects from closure A1075 orphaned_objects_gc_test.go:292: - Deleted: 2 objects from closure B1076 orphaned_objects_gc_test.go:293: - Deleted: 6 orphaned chain objects (X1->X2->X3)1077 orphaned_objects_gc_test.go:294: - Deleted: 2 orphaned single objects (Y)1078 orphaned_objects_gc_test.go:295: - Total deleted: 10 objects1079--- PASS: TestOrphanedObjectsGC (1.03s)1080=== CONT TestGCSweepSkipsPendingObjects1081--- PASS: TestRegisterCompletedObjectNeedsPendingRow (0.51s)1082=== CONT TestClientCADerivations1083--- PASS: TestMetricsInventory (0.51s)1084=== CONT TestClientSharedPathCommittedMidPush10852026/10/06 10:46:42 INFO Received cleanup request method=DELETE path=/api/pending_closures10862026/10/06 10:46:42 WARN Failed to abort upload, keeping its closure key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst error="Get \"http://127.0.0.1:1/bucket36/?location=\": dial tcp 127.0.0.1:1: connect: connection refused" code=""10872026/10/06 10:46:42 INFO Aborted multipart uploads count=0 kept=110882026/10/06 10:46:42 INFO Received cleanup request method=DELETE path=/api/pending_closures10892026/10/06 10:46:42 INFO Aborted multipart uploads count=1 kept=01090--- PASS: TestMultipartCleanup (0.70s)1091=== CONT TestClientWithDependencies10922026/10/06 10:46:42 WARN readiness check failed error="closed pool"1093--- PASS: TestService_readinessHandler (0.52s)1094=== CONT TestDeduplicatedObjectsRecordedAsPending1095=== NAME TestNARDeduplicationMetadataUploadBug1096 metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug943705167/001/store/y00vibypbvv2r46ympv3nbn7zqpz4h6c-file1.txt1097--- PASS: TestService_healthCheckHandler (0.53s)1098=== CONT TestClientMultipleUploads1099--- PASS: TestReadProxyInvalidPath (0.56s)1100=== CONT TestClientFallsBackToClosures11012026/10/06 10:46:42 INFO Received push request method=POST path=/api/pushes11022026/10/06 10:46:42 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)11032026/10/06 10:46:42 INFO Uploading y00vibypbvv2r46ympv3nbn7zqpz4h6c-file1.txt (160B)11042026/10/06 10:46:42 WARN Failed to register uploaded object key=nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst error="server returned 404: 404 page not found\n"11052026/10/06 10:46:42 WARN Failed to register uploaded object key=y00vibypbvv2r46ympv3nbn7zqpz4h6c.ls error="server returned 404: 404 page not found\n"11062026/10/06 10:46:42 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign11072026/10/06 10:46:42 INFO Signed narinfos id=1 count=111082026/10/06 10:46:42 INFO Uploading 1 narinfos1109--- PASS: TestReadProxyDisabled (0.54s)1110=== CONT TestPushDedupSurvivesConcurrentGC11112026/10/06 10:46:42 INFO Received complete push request method=POST path=/api/pushes/1/complete11122026/10/06 10:46:42 WARN Failed to register uploaded object key=y00vibypbvv2r46ympv3nbn7zqpz4h6c.narinfo error="server returned 404: 404 page not found\n"11132026/10/06 10:46:42 INFO Upload complete. (64ms)1114=== NAME TestNARDeduplicationMetadataUploadBug1115 metadata_upload_test.go:54: Retrieved narinfo from S3:1116 StorePath: /build/TestNARDeduplicationMetadataUploadBug943705167/001/store/y00vibypbvv2r46ympv3nbn7zqpz4h6c-file1.txt1117 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1118 Compression: zstd1119 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1120 NarSize: 1601121 References: 1122 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1123 metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1124 metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1125 {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1126--- PASS: TestReadRedirectNar (0.60s)1127=== CONT TestClientIntegration1128=== NAME TestNARDeduplicationMetadataUploadBug1129 metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug943705167/001/store/88xksy6yhalr4zsc42mih1fz20w5zw2f-file2.txt11302026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures1131--- PASS: TestPushWaitsForSweepHoldingRow (1.17s)1132=== CONT TestClientPushesUseOnePush1133--- PASS: TestReadProxyRootRedirectsToIndexHTML (0.48s)1134=== CONT TestClientErrorHandling1135=== RUN TestClientErrorHandling/InvalidStorePath1136=== PAUSE TestClientErrorHandling/InvalidStorePath1137=== RUN TestClientErrorHandling/InvalidAuthToken1138=== PAUSE TestClientErrorHandling/InvalidAuthToken1139=== RUN TestClientErrorHandling/ServerNotAvailable1140=== PAUSE TestClientErrorHandling/ServerNotAvailable1141=== CONT TestGCMetrics1142--- PASS: TestReadProxyConditionalGet (0.47s)1143=== CONT TestService_RequireScope_OIDC11442026/10/06 10:46:42 INFO Received push request method=POST path=/api/pushes11452026/10/06 10:46:42 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)11462026/10/06 10:46:42 WARN Failed to register uploaded object key=88xksy6yhalr4zsc42mih1fz20w5zw2f.ls error="server returned 404: 404 page not found\n"11472026/10/06 10:46:42 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign11482026/10/06 10:46:42 INFO Signed narinfos id=2 count=111492026/10/06 10:46:42 INFO Uploading 1 narinfos11502026/10/06 10:46:42 INFO Received complete push request method=POST path=/api/pushes/2/complete11512026/10/06 10:46:42 WARN Failed to register uploaded object key=88xksy6yhalr4zsc42mih1fz20w5zw2f.narinfo error="server returned 404: 404 page not found\n"11522026/10/06 10:46:42 INFO Upload complete. (55ms)1153=== NAME TestNARDeduplicationMetadataUploadBug1154 metadata_upload_test.go:76: Retrieved narinfo from S3:1155 StorePath: /build/TestNARDeduplicationMetadataUploadBug943705167/001/store/88xksy6yhalr4zsc42mih1fz20w5zw2f-file2.txt1156 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1157 Compression: zstd1158 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1159 NarSize: 1601160 References: 1161 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf11622026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures1163 metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1164 metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1165 {"version":1,"root":{"type":"regular","size":44}}1166--- PASS: TestNARDeduplicationMetadataUploadBug (0.78s)1167=== CONT TestGCBugBareHashReferences11682026/10/06 10:46:42 INFO Aborted multipart uploads count=0 kept=011692026/10/06 10:46:42 WARN Force mode enabled - objects will be deleted immediately without grace period11702026/10/06 10:46:42 ERROR failed to remove object object=nar/dddddddddddddddddddddddddddddddddddddddddddddddddddd.nar.zst error="Access Denied."11712026/10/06 10:46:42 ERROR failed to remove object object=dddddddddddddddddddddddddddddddd.narinfo error="Access Denied."1172--- PASS: TestFailedS3DeleteKeepsTombstone (0.58s)1173=== CONT TestCacheStatsHandler11742026/10/06 10:46:42 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:43833/oidc11752026/10/06 10:46:42 INFO Aborted multipart uploads count=0 kept=011762026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures11772026/10/06 10:46:42 WARN Force mode enabled - objects will be deleted immediately without grace period11782026/10/06 10:46:42 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1179--- PASS: TestTombstonedObjectOfferedWithoutWaiting (0.52s)1180=== CONT TestGCAdvisoryLockBlocksConcurrentRun11812026/10/06 10:46:42 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=011822026/10/06 10:46:42 INFO Vacuumed table table=pending_closures11832026/10/06 10:46:42 INFO Vacuumed table table=pending_objects11842026/10/06 10:46:42 INFO Vacuumed table table=multipart_uploads11852026/10/06 10:46:42 INFO Vacuumed table table=closures11862026/10/06 10:46:42 WARN Failed to abort redundant multipart upload, keeping its row object_key=nar/gggggggggggggggggggggggggggggg0200000000000000000000.nar.zst upload_id=M2ExZjdlNGItNzdjMC00YzYzLThjMDktYzM0YTQ1ZWQ0ZmI2LmQ1MTA1YjZiLWU4MDEtNDJkYi1iNDRlLTAyNDliMzk3ODE3NXgxNzkxMjgzNjAyMTMxMjgzMTEw error="Get \"http://127.0.0.1:1/bucket16/?location=\": dial tcp 127.0.0.1:1: connect: connection refused"11872026/10/06 10:46:42 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0200000000000000000000.nar.zst upload_id=M2ExZjdlNGItNzdjMC00YzYzLThjMDktYzM0YTQ1ZWQ0ZmI2LmY3YzM4ODM5LTA5NWYtNGY2OC1iYTEyLTkzMmRiNjVjN2M4N3gxNzkxMjgzNjAyMTIxOTU5MTA3 parts=1211882026/10/06 10:46:42 INFO Received cleanup request method=DELETE path=/api/pending_closures11892026/10/06 10:46:42 INFO Vacuumed table table=objects1190--- PASS: TestGCSweepDeliversEachKeyOnce (0.55s)1191=== CONT TestCacheConfigHandler1192=== RUN TestCacheConfigHandler/full_config,_no_issuer1193=== PAUSE TestCacheConfigHandler/full_config,_no_issuer1194=== RUN TestCacheConfigHandler/no_cache_url_configured1195=== PAUSE TestCacheConfigHandler/no_cache_url_configured1196=== RUN TestCacheConfigHandler/no_signing_keys1197=== PAUSE TestCacheConfigHandler/no_signing_keys1198=== RUN TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1199=== PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1200=== CONT TestPinProtectsFromGC1201--- PASS: TestReadProxyHead (0.51s)1202=== CONT TestLeadEndsOnShutdown12032026/10/06 10:46:42 INFO Aborted multipart uploads count=1 kept=01204--- PASS: TestRedundantMultipartUpload (1.89s)1205=== CONT TestService_ReadScope_PublicByDefault12062026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures1207--- PASS: TestConcurrentCommitsSharingObjectsDoNotDeadlock (0.89s)1208=== CONT TestLeadIncumbentAnnouncesAtOnce12092026/10/06 10:46:42 INFO Aborted multipart uploads count=0 kept=012102026/10/06 10:46:42 WARN Force mode enabled - objects will be deleted immediately without grace period12112026/10/06 10:46:42 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=012122026/10/06 10:46:42 INFO Vacuumed table table=pending_closures12132026/10/06 10:46:42 INFO Vacuumed table table=pending_objects12142026/10/06 10:46:42 INFO Vacuumed table table=multipart_uploads12152026/10/06 10:46:42 INFO Vacuumed table table=closures12162026/10/06 10:46:42 INFO Vacuumed table table=objects1217--- PASS: TestCreatePendingClosureVerifyS3FailureReleasesConnection (0.69s)1218=== CONT TestReadProxyNarStreaming1219--- PASS: TestGCSweepSkipsPendingObjects (0.55s)1220=== CONT TestLeadElectsOneAndHandsOver12212026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures1222--- PASS: TestDeduplicatedObjectsRecordedAsPending (0.55s)1223=== CONT TestReadProxy4041224=== NAME TestClientCADerivations1225 client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations2876782634/001/store/9vs4n4rp3s0sm0hh4gg88cvqkkddgrw6-ca-test1226=== NAME TestClientWithDependencies1227 client_integration_test.go:613: Built derivation: /build/TestClientWithDependencies239069307/001/store/4r445xp7lfjbq7w8hz7pdfb4chyr6q79-test-script1228=== NAME TestClientCADerivations1229 client_ca_test.go:139: Found 1 dependencies (including self)1230=== NAME TestClientMultipleUploads1231 client_integration_test.go:358: Created store path 0: /build/TestClientMultipleUploads4144538635/001/store/k4l06yz3d049vxf7224xc47ld35hjiw3-test-file-0.txt1232=== NAME TestClientWithDependencies1233 client_integration_test.go:615: Found 1 dependencies (including self)12342026/10/06 10:46:42 INFO Received uploads request method=POST path=/api/pending_closures1235=== NAME TestClientMultipleUploads1236 client_integration_test.go:358: Created store path 1: /build/TestClientMultipleUploads4144538635/001/store/xgjvcmwhkxhj9cxfrmxkmjyvm63m3zip-test-file-1.txt12372026/10/06 10:46:42 INFO Received push request method=POST path=/api/pushes12382026/10/06 10:46:42 INFO Uploading 2 paths to 127.0.0.1 (0 already cached)12392026/10/06 10:46:42 INFO Uploading 3p4hrg1r3pal48sw941hca2af63g1ix6-shared-dep (136B)12402026/10/06 10:46:42 INFO Uploading vs6h1gxmrky7njza63pah3xsx8xvcf9m-top (224B)12412026/10/06 10:46:42 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"12422026/10/06 10:46:42 WARN Failed to register uploaded object key=nar/1g4rpaf1fw5dhzdcy7agyxljwd8y1i5jxxzvakqdsz1hzyf1m87v.nar.zst error="server returned 404: 404 page not found\n"12432026/10/06 10:46:42 WARN Failed to register uploaded object key=3p4hrg1r3pal48sw941hca2af63g1ix6.ls error="server returned 404: 404 page not found\n"12442026/10/06 10:46:42 WARN Failed to register uploaded object key=vs6h1gxmrky7njza63pah3xsx8xvcf9m.ls error="server returned 404: 404 page not found\n"12452026/10/06 10:46:42 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign12462026/10/06 10:46:42 INFO Signed narinfos id=1 count=212472026/10/06 10:46:42 INFO Uploading 2 narinfos12482026/10/06 10:46:42 WARN Failed to register uploaded object key=vs6h1gxmrky7njza63pah3xsx8xvcf9m.narinfo error="server returned 404: 404 page not found\n"12492026/10/06 10:46:42 INFO Received complete push request method=POST path=/api/pushes/1/complete12502026/10/06 10:46:42 WARN Failed to register uploaded object key=3p4hrg1r3pal48sw941hca2af63g1ix6.narinfo error="server returned 404: 404 page not found\n"1251 client_integration_test.go:358: Created store path 2: /build/TestClientMultipleUploads4144538635/001/store/x3v4v0dzhsvk9cmng0f567j7hm3bp4yh-test-file-2.txt12522026/10/06 10:46:42 INFO Upload complete. (77ms)1253=== NAME TestClientSharedPathCommittedMidPush1254 client_integration_test.go:680: Retrieved narinfo from S3:1255 StorePath: /build/TestClientSharedPathCommittedMidPush4040444039/001/store/3p4hrg1r3pal48sw941hca2af63g1ix6-shared-dep1256 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1257 Compression: zstd1258 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821259 NarSize: 1361260 References: 1261 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1262 client_integration_test.go:680: Retrieved narinfo from S3:1263 StorePath: /build/TestClientSharedPathCommittedMidPush4040444039/001/store/vs6h1gxmrky7njza63pah3xsx8xvcf9m-top1264 URL: nar/1g4rpaf1fw5dhzdcy7agyxljwd8y1i5jxxzvakqdsz1hzyf1m87v.nar.zst1265 Compression: zstd1266 NarHash: sha256:1g4rpaf1fw5dhzdcy7agyxljwd8y1i5jxxzvakqdsz1hzyf1m87v1267 NarSize: 2241268 References: /build/TestClientSharedPathCommittedMidPush4040444039/001/store/3p4hrg1r3pal48sw941hca2af63g1ix6-shared-dep1269 CA: text:sha256:0x8ia1xb9a9c8jxqhy4il8x7v3yfw2fpamdan5ip9q9afsxrbbca1270=== NAME TestClientIntegration1271 client_integration_test.go:286: Created store path: /build/TestClientIntegration1601525743/002/store/fk7vdhhnm8rjkk5cfmsblqw99cqx3xd3-test-file.txt1272--- PASS: TestClientSharedPathCommittedMidPush (0.74s)1273=== CONT TestReadProxyNarinfoAlreadyDecompressed12742026/10/06 10:46:42 INFO Received push request method=POST path=/api/pushes12752026/10/06 10:46:42 INFO Received push request method=POST path=/api/pushes12762026/10/06 10:46:42 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)12772026/10/06 10:46:42 INFO Uploading 9vs4n4rp3s0sm0hh4gg88cvqkkddgrw6-ca-test (144B)12782026/10/06 10:46:42 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)12792026/10/06 10:46:42 INFO Uploading 4r445xp7lfjbq7w8hz7pdfb4chyr6q79-test-script (136B)12802026/10/06 10:46:42 WARN Failed to register uploaded object key=9vs4n4rp3s0sm0hh4gg88cvqkkddgrw6.ls error="server returned 404: 404 page not found\n"12812026/10/06 10:46:42 INFO Aborted multipart uploads count=0 kept=012822026/10/06 10:46:42 WARN Failed to register uploaded object key=nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst error="server returned 404: 404 page not found\n"12832026/10/06 10:46:42 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign12842026/10/06 10:46:42 WARN Failed to register uploaded object key=log/0vlaa9a2v5fgcadxzzb7d7xcqy68bq8i-ca-test.drv error="server returned 404: 404 page not found\n"12852026/10/06 10:46:42 INFO Signed narinfos id=1 count=112862026/10/06 10:46:42 INFO Uploading 1 narinfos12872026/10/06 10:46:42 WARN Failed to register uploaded object key=4r445xp7lfjbq7w8hz7pdfb4chyr6q79.ls error="server returned 404: 404 page not found\n"12882026/10/06 10:46:42 WARN Failed to register uploaded object key=log/gyxx3840api1xqfrbil0kbdjv0n1iz2g-test-script.drv error="server returned 404: 404 page not found\n"12892026/10/06 10:46:42 WARN Force mode enabled - objects will be deleted immediately without grace period12902026/10/06 10:46:42 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign12912026/10/06 10:46:42 WARN Failed to register uploaded object key=nar/1s7yghixzpinyqm7g59kyn3sav81pmnsdi5v63m6x4r2vacy907j.nar.zst error="server returned 404: 404 page not found\n"12922026/10/06 10:46:42 INFO Signed narinfos id=1 count=112932026/10/06 10:46:42 INFO Uploading 1 narinfos12942026/10/06 10:46:42 INFO Received complete push request method=POST path=/api/pushes/1/complete12952026/10/06 10:46:42 WARN Failed to register uploaded object key=9vs4n4rp3s0sm0hh4gg88cvqkkddgrw6.narinfo error="server returned 404: 404 page not found\n"12962026/10/06 10:46:42 INFO Aborted multipart uploads count=0 kept=012972026/10/06 10:46:42 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=012982026/10/06 10:46:42 INFO Vacuumed table table=pending_closures12992026/10/06 10:46:42 INFO Vacuumed table table=pending_objects13002026/10/06 10:46:42 INFO Vacuumed table table=multipart_uploads13012026/10/06 10:46:42 INFO Vacuumed table table=closures13022026/10/06 10:46:42 INFO Received complete push request method=POST path=/api/pushes/1/complete13032026/10/06 10:46:42 WARN Failed to register uploaded object key=4r445xp7lfjbq7w8hz7pdfb4chyr6q79.narinfo error="server returned 404: 404 page not found\n"13042026/10/06 10:46:42 INFO Vacuumed table table=objects13052026/10/06 10:46:42 INFO Upload complete. (80ms)13062026/10/06 10:46:42 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=01307=== NAME TestClientCADerivations1308 client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations2876782634/001/store/9vs4n4rp3s0sm0hh4gg88cvqkkddgrw6-ca-test1309 URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1310 Compression: zstd1311 NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1312 NarSize: 1441313 References: 1314 Deriver: /build/TestClientCADerivations2876782634/001/store/0vlaa9a2v5fgcadxzzb7d7xcqy68bq8i-ca-test.drv1315 CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1316 client_ca_test.go:185: Checking for realisation files in S3...13172026/10/06 10:46:42 INFO Vacuumed table table=pending_closures1318 client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1319 client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache13202026/10/06 10:46:42 INFO Vacuumed table table=pending_objects13212026/10/06 10:46:42 INFO Vacuumed table table=multipart_uploads1322--- PASS: TestGCMetrics (0.51s)1323=== CONT TestConnectWaitsForAPeerMigration13242026/10/06 10:46:42 INFO Upload complete. (62ms)13252026/10/06 10:46:42 INFO Vacuumed table table=closures13262026/10/06 10:46:42 INFO Vacuumed table table=objects1327=== NAME TestClientWithDependencies1328 client_integration_test.go:617: Skipping nix copy test - isolated store (/build/TestClientWithDependencies239069307/001/store) requires matching store prefix1329--- PASS: TestClientWithDependencies (0.75s)13302026/10/06 10:46:43 INFO Aborted multipart uploads count=0 kept=01331=== CONT TestService_ReadAuthMiddleware13322026/10/06 10:46:43 WARN Force mode enabled - objects will be deleted immediately without grace period13332026/10/06 10:46:43 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=013342026/10/06 10:46:43 INFO Vacuumed table table=pending_closures13352026/10/06 10:46:43 INFO Vacuumed table table=pending_objects13362026/10/06 10:46:43 INFO Vacuumed table table=multipart_uploads13372026/10/06 10:46:43 INFO Vacuumed table table=closures13382026/10/06 10:46:43 INFO Vacuumed table table=objects1339--- PASS: TestPushDedupSurvivesConcurrentGC (0.62s)1340=== CONT TestReadProxyContentNegotiation13412026/10/06 10:46:43 INFO Received push request method=POST path=/api/pushes13422026/10/06 10:46:43 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)13432026/10/06 10:46:43 INFO Uploading k4l06yz3d049vxf7224xc47ld35hjiw3-test-file-0.txt (160B)13442026/10/06 10:46:43 INFO Uploading x3v4v0dzhsvk9cmng0f567j7hm3bp4yh-test-file-2.txt (160B)13452026/10/06 10:46:43 INFO Uploading xgjvcmwhkxhj9cxfrmxkmjyvm63m3zip-test-file-1.txt (160B)13462026/10/06 10:46:43 WARN Failed to register uploaded object key=nar/13p9pqi0ga6fx2apcwls9hjnv7nmf740ggpn5zvfzdsp5vz2bbg4.nar.zst error="server returned 404: 404 page not found\n"13472026/10/06 10:46:43 WARN Failed to register uploaded object key=k4l06yz3d049vxf7224xc47ld35hjiw3.ls error="server returned 404: 404 page not found\n"13482026/10/06 10:46:43 WARN Failed to register uploaded object key=xgjvcmwhkxhj9cxfrmxkmjyvm63m3zip.ls error="server returned 404: 404 page not found\n"13492026/10/06 10:46:43 WARN Failed to register uploaded object key=x3v4v0dzhsvk9cmng0f567j7hm3bp4yh.ls error="server returned 404: 404 page not found\n"13502026/10/06 10:46:43 WARN Failed to register uploaded object key=nar/0ahq3izw3h0b0c08p3xdfclh758fwxnp85ccvflasb4vnfsv50ri.nar.zst error="server returned 404: 404 page not found\n"13512026/10/06 10:46:43 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign13522026/10/06 10:46:43 WARN Failed to register uploaded object key=nar/1823g6vx4q5zqvr73m7lkq1wpk40fin7bsc792di430yk7sg4lka.nar.zst error="server returned 404: 404 page not found\n"13532026/10/06 10:46:43 INFO Signed narinfos id=1 count=313542026/10/06 10:46:43 INFO Uploading 3 narinfos1355--- PASS: TestCacheStatsHandler (0.47s)1356=== CONT TestResolveDBConnectionString1357=== RUN TestResolveDBConnectionString/flag_wins1358=== PAUSE TestResolveDBConnectionString/flag_wins1359=== RUN TestResolveDBConnectionString/file_when_flag_empty1360=== PAUSE TestResolveDBConnectionString/file_when_flag_empty1361=== RUN TestResolveDBConnectionString/missing_file_is_an_error1362=== PAUSE TestResolveDBConnectionString/missing_file_is_an_error1363=== RUN TestResolveDBConnectionString/PGHOST_allows_empty1364=== PAUSE TestResolveDBConnectionString/PGHOST_allows_empty1365=== RUN TestResolveDBConnectionString/nothing_configured1366=== PAUSE TestResolveDBConnectionString/nothing_configured1367=== CONT TestService_AuthMiddleware_OIDC13682026/10/06 10:46:43 WARN Failed to register uploaded object key=k4l06yz3d049vxf7224xc47ld35hjiw3.narinfo error="server returned 404: 404 page not found\n"13692026/10/06 10:46:43 INFO Received complete push request method=POST path=/api/pushes/1/complete13702026/10/06 10:46:43 WARN Failed to register uploaded object key=x3v4v0dzhsvk9cmng0f567j7hm3bp4yh.narinfo error="server returned 404: 404 page not found\n"13712026/10/06 10:46:43 WARN Failed to register uploaded object key=xgjvcmwhkxhj9cxfrmxkmjyvm63m3zip.narinfo error="server returned 404: 404 page not found\n"13722026/10/06 10:46:43 INFO Upload complete. (66ms)1373=== NAME TestClientMultipleUploads1374 client_integration_test.go:369: Uploaded 3 paths in 93.504259ms13752026/10/06 10:46:43 INFO Received push request method=POST path=/api/pushes13762026/10/06 10:46:43 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)13772026/10/06 10:46:43 INFO Uploading fk7vdhhnm8rjkk5cfmsblqw99cqx3xd3-test-file.txt (152B)13782026/10/06 10:46:43 INFO Received uploads request method=POST path=/api/pending_closures1379--- PASS: TestClientMultipleUploads (0.76s)1380=== CONT TestIsValidCachePath1381=== RUN TestIsValidCachePath/narinfo1382=== PAUSE TestIsValidCachePath/narinfo1383=== RUN TestIsValidCachePath/narinfo_all_nix_base32_chars1384=== PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars1385=== RUN TestIsValidCachePath/nar_zst1386=== PAUSE TestIsValidCachePath/nar_zst1387=== RUN TestIsValidCachePath/nar_xz1388=== PAUSE TestIsValidCachePath/nar_xz1389=== RUN TestIsValidCachePath/nar_bz21390=== PAUSE TestIsValidCachePath/nar_bz21391=== RUN TestIsValidCachePath/nar_uncompressed1392=== PAUSE TestIsValidCachePath/nar_uncompressed1393=== RUN TestIsValidCachePath/ls1394=== PAUSE TestIsValidCachePath/ls1395=== RUN TestIsValidCachePath/log1396=== PAUSE TestIsValidCachePath/log1397=== RUN TestIsValidCachePath/realisation1398=== PAUSE TestIsValidCachePath/realisation1399=== RUN TestIsValidCachePath/nix-cache-info1400=== PAUSE TestIsValidCachePath/nix-cache-info1401=== RUN TestIsValidCachePath/index.html14022026/10/06 10:46:43 WARN Failed to register uploaded object key=nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst error="server returned 404: 404 page not found\n"1403=== PAUSE TestIsValidCachePath/index.html14042026/10/06 10:46:43 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1405=== RUN TestIsValidCachePath/traversal_parent14062026/10/06 10:46:43 WARN Failed to register uploaded object key=fk7vdhhnm8rjkk5cfmsblqw99cqx3xd3.ls error="server returned 404: 404 page not found\n"1407=== PAUSE TestIsValidCachePath/traversal_parent1408=== RUN TestIsValidCachePath/traversal_in_middle1409=== PAUSE TestIsValidCachePath/traversal_in_middle14102026/10/06 10:46:43 INFO Signed narinfos id=1 count=11411=== RUN TestIsValidCachePath/invalid_char_e1412=== PAUSE TestIsValidCachePath/invalid_char_e1413=== RUN TestIsValidCachePath/invalid_char_u14142026/10/06 10:46:43 INFO Uploading 1 narinfos1415=== PAUSE TestIsValidCachePath/invalid_char_u1416=== RUN TestIsValidCachePath/random_path1417=== PAUSE TestIsValidCachePath/random_path1418=== RUN TestIsValidCachePath/empty1419=== PAUSE TestIsValidCachePath/empty1420=== RUN TestIsValidCachePath/leading_slash1421=== PAUSE TestIsValidCachePath/leading_slash1422=== RUN TestIsValidCachePath/wrong_extension1423=== PAUSE TestIsValidCachePath/wrong_extension1424=== RUN TestIsValidCachePath/short_hash1425=== PAUSE TestIsValidCachePath/short_hash1426=== CONT TestReadProxyNarinfo14272026/10/06 10:46:43 INFO Received uploads request method=POST path=/api/pending_closures14282026/10/06 10:46:43 INFO Received complete push request method=POST path=/api/pushes/1/complete14292026/10/06 10:46:43 WARN Failed to register uploaded object key=fk7vdhhnm8rjkk5cfmsblqw99cqx3xd3.narinfo error="server returned 404: 404 page not found\n"1430=== RUN TestService_RequireScope_OIDC/builder_may_write1431=== PAUSE TestService_RequireScope_OIDC/builder_may_write1432=== RUN TestService_RequireScope_OIDC/builder_may_not_admin1433=== PAUSE TestService_RequireScope_OIDC/builder_may_not_admin1434=== RUN TestService_RequireScope_OIDC/ops_may_admin1435=== PAUSE TestService_RequireScope_OIDC/ops_may_admin1436=== RUN TestService_RequireScope_OIDC/ops_may_not_write1437=== PAUSE TestService_RequireScope_OIDC/ops_may_not_write1438=== RUN TestService_RequireScope_OIDC/reader_may_not_write1439=== PAUSE TestService_RequireScope_OIDC/reader_may_not_write1440=== RUN TestService_RequireScope_OIDC/static_token_may_admin1441=== PAUSE TestService_RequireScope_OIDC/static_token_may_admin1442=== RUN TestService_RequireScope_OIDC/static_token_may_write1443=== PAUSE TestService_RequireScope_OIDC/static_token_may_write1444=== RUN TestService_RequireScope_OIDC/reader_may_read1445=== PAUSE TestService_RequireScope_OIDC/reader_may_read1446=== RUN TestService_RequireScope_OIDC/writer_implies_read1447=== PAUSE TestService_RequireScope_OIDC/writer_implies_read1448=== RUN TestService_RequireScope_OIDC/anonymous_may_not_read1449=== PAUSE TestService_RequireScope_OIDC/anonymous_may_not_read1450=== RUN TestService_RequireScope_OIDC/reader_may_read_with_netrc1451=== PAUSE TestService_RequireScope_OIDC/reader_may_read_with_netrc1452=== RUN TestService_RequireScope_OIDC/static_token_may_admin_with_netrc1453=== PAUSE TestService_RequireScope_OIDC/static_token_may_admin_with_netrc1454=== RUN TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read1455=== PAUSE TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read1456=== RUN TestService_RequireScope_OIDC/empty_netrc_password_may_not_read1457=== PAUSE TestService_RequireScope_OIDC/empty_netrc_password_may_not_read1458=== CONT TestService_AuthMiddleware_MTLSBoundSubjects14592026/10/06 10:46:43 INFO Uploading 2 paths to 127.0.0.1 (1 already cached)14602026/10/06 10:46:43 INFO Uploading g6z41p868biz0xlgp2hsljnn31yxw9mr-shared-dep (136B)14612026/10/06 10:46:43 INFO Uploading ll8idhigms9mklyl9wrxxih36scn9gxm-a (216B)14622026/10/06 10:46:43 INFO Upload complete. (59ms)14632026/10/06 10:46:43 WARN Failed to register uploaded object key=6w20p9y87nwk898c7hkrb4bbrn9a9ra2.ls error="server returned 404: 404 page not found\n"14642026/10/06 10:46:43 WARN Failed to register uploaded object key=ll8idhigms9mklyl9wrxxih36scn9gxm.ls error="server returned 404: 404 page not found\n"14652026/10/06 10:46:43 WARN Failed to register uploaded object key=nar/17cm6919bbz582my2821hj7jan022pdp5mnfakqpv1rpbm7mfpnr.nar.zst error="server returned 404: 404 page not found\n"14662026/10/06 10:46:43 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"14672026/10/06 10:46:43 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign14682026/10/06 10:46:43 WARN Failed to register uploaded object key=g6z41p868biz0xlgp2hsljnn31yxw9mr.ls error="server returned 404: 404 page not found\n"14692026/10/06 10:46:43 INFO Signed narinfos id=1 count=214702026/10/06 10:46:43 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign14712026/10/06 10:46:43 INFO Signed narinfos id=2 count=214722026/10/06 10:46:43 INFO Uploading 4 narinfos14732026/10/06 10:46:43 WARN Failed to register uploaded object key=ll8idhigms9mklyl9wrxxih36scn9gxm.narinfo error="server returned 404: 404 page not found\n"14742026/10/06 10:46:43 ERROR failed to check GC advisory lock error="query pg_locks: failed to connect to `user=nixbld database=niks3`: /nonexistent/.s.PGSQL.5432 (/nonexistent): dial error: dial unix /nonexistent/.s.PGSQL.5432: connect: no such file or directory"14752026/10/06 10:46:43 WARN Failed to register uploaded object key=g6z41p868biz0xlgp2hsljnn31yxw9mr.narinfo error="server returned 404: 404 page not found\n"1476--- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.44s)1477=== CONT TestService_AuthMiddleware_MTLSProxyHeader14782026/10/06 10:46:43 WARN Failed to register uploaded object key=6w20p9y87nwk898c7hkrb4bbrn9a9ra2.narinfo error="server returned 404: 404 page not found\n"14792026/10/06 10:46:43 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete14802026/10/06 10:46:43 WARN Failed to register uploaded object key=g6z41p868biz0xlgp2hsljnn31yxw9mr.narinfo error="server returned 404: 404 page not found\n"1481--- PASS: TestService_ReadScope_PublicByDefault (0.42s)1482=== CONT TestProxyHeadersOnlyTrustedOnSocket14832026/10/06 10:46:43 INFO Completed upload id=114842026/10/06 10:46:43 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1485=== NAME TestClientCADerivations1486 client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1487 warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1488 error: binary cache 's3://bucket56?endpoint=http://localhost:39827®ion=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations2876782634/001/store'1489 client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 114902026/10/06 10:46:43 INFO Completed upload id=214912026/10/06 10:46:43 INFO All 1 paths already cached14922026/10/06 10:46:43 INFO Upload complete. (97ms)1493=== NAME TestClientIntegration1494 client_integration_test.go:312: Retrieved narinfo from S3:1495 StorePath: /build/TestClientIntegration1601525743/002/store/fk7vdhhnm8rjkk5cfmsblqw99cqx3xd3-test-file.txt1496 URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1497 Compression: zstd1498 NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11499 NarSize: 1521500 References: 1501 CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11502=== NAME TestClientFallsBackToClosures1503 client_pushes_test.go:112: Retrieved narinfo from S3:1504 StorePath: /build/TestClientFallsBackToClosures1128913157/001/store/g6z41p868biz0xlgp2hsljnn31yxw9mr-shared-dep1505 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1506 Compression: zstd1507 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821508 NarSize: 1361509 References: 1510 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1511=== NAME TestClientIntegration1512 client_integration_test.go:313: Retrieved .ls file from S3 (compressed size: 77 bytes)1513 client_integration_test.go:313: Decompressed .ls content (64 bytes):1514 {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1515 client_integration_test.go:316: Testing garbage collection...1516=== NAME TestClientFallsBackToClosures1517 client_pushes_test.go:112: Retrieved narinfo from S3:1518 StorePath: /build/TestClientFallsBackToClosures1128913157/001/store/ll8idhigms9mklyl9wrxxih36scn9gxm-a1519 URL: nar/17cm6919bbz582my2821hj7jan022pdp5mnfakqpv1rpbm7mfpnr.nar.zst1520 Compression: zstd1521 NarHash: sha256:17cm6919bbz582my2821hj7jan022pdp5mnfakqpv1rpbm7mfpnr1522 NarSize: 2161523 References: /build/TestClientFallsBackToClosures1128913157/001/store/g6z41p868biz0xlgp2hsljnn31yxw9mr-shared-dep1524 CA: text:sha256:1b0p86xly4czbc62svws7xcs5c3i61hzijs0krf902m04pf0qdb81525--- PASS: TestClientCADerivations (0.90s)1526=== CONT TestProxyWriteTimeout/narinfo1527=== CONT TestProxyWriteTimeout/10_GiB_nar1528=== CONT TestProxyWriteTimeout/unknown_size1529=== CONT TestProxyWriteTimeout/1_GiB_nar1530--- PASS: TestProxyWriteTimeout (0.09s)1531 --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1532 --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1533 --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1534 --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)1535=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info15362026/10/06 10:46:43 INFO Received uploads request method=POST path=/1537=== CONT TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key15382026/10/06 10:46:43 INFO Received request for more parts method=POST path=/1539=== CONT TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1540=== NAME TestClientFallsBackToClosures15412026/10/06 10:46:43 INFO Received complete multipart upload request method=POST path=/1542 client_pushes_test.go:112: Retrieved narinfo from S3:1543 StorePath: /build/TestClientFallsBackToClosures1128913157/001/store/6w20p9y87nwk898c7hkrb4bbrn9a9ra2-b1544 URL: nar/17cm6919bbz582my2821hj7jan022pdp5mnfakqpv1rpbm7mfpnr.nar.zst1545 Compression: zstd1546 NarHash: sha256:17cm6919bbz582my2821hj7jan022pdp5mnfakqpv1rpbm7mfpnr1547 NarSize: 2161548 References: /build/TestClientFallsBackToClosures1128913157/001/store/g6z41p868biz0xlgp2hsljnn31yxw9mr-shared-dep1549 CA: text:sha256:1b0p86xly4czbc62svws7xcs5c3i61hzijs0krf902m04pf0qdb81550=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal15512026/10/06 10:46:43 INFO Received uploads request method=POST path=/1552--- PASS: TestUploadHandlersRejectInvalidKeys (0.09s)1553 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1554 --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1555 --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1556 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1557=== CONT TestPendingClosureWriteTimeout/empty1558=== CONT TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline1559--- PASS: TestClientFallsBackToClosures (0.79s)1560=== CONT TestPendingClosureWriteTimeout/670k_objects1561=== CONT TestPendingClosureWriteTimeout/400_objects1562=== CONT TestParseSingleRange/none1563=== CONT TestParseSingleRange/start_far_past_EOF1564=== CONT TestParseSingleRange/start_past_EOF1565=== CONT TestParseSingleRange/single_byte1566=== CONT TestParseSingleRange/malformed_end_before_start1567=== CONT TestParseSingleRange/malformed_both_empty1568=== CONT TestParseSingleRange/malformed_no_dash1569=== CONT TestParseSingleRange/multi-range_ignored1570=== CONT TestParseSingleRange/unknown_unit1571=== CONT TestParseSingleRange/suffix1572=== CONT TestParseSingleRange/suffix_exceeds_size1573=== CONT TestParseSingleRange/end_clamped_to_size1574=== CONT TestParseSingleRange/open-ended1575=== CONT TestParseSingleRange/closed1576--- PASS: TestParseSingleRange (0.09s)1577 --- PASS: TestParseSingleRange/none (0.00s)1578 --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1579 --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1580 --- PASS: TestParseSingleRange/single_byte (0.00s)1581 --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1582 --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1583 --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1584 --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1585 --- PASS: TestParseSingleRange/unknown_unit (0.00s)1586 --- PASS: TestParseSingleRange/suffix (0.00s)1587 --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1588 --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1589 --- PASS: TestParseSingleRange/open-ended (0.00s)1590 --- PASS: TestParseSingleRange/closed (0.00s)1591=== CONT TestIsValidUploadKey/narinfo1592=== CONT TestIsValidUploadKey/empty_key1593=== CONT TestIsValidUploadKey/absolute1594=== CONT TestIsValidUploadKey/traversal_nar1595=== CONT TestIsValidUploadKey/traversal1596=== CONT TestIsValidUploadKey/listing_key,_narinfo_type1597=== CONT TestIsValidUploadKey/nar_key,_narinfo_type1598=== CONT TestIsValidUploadKey/narinfo_key,_nar_type1599=== CONT TestIsValidUploadKey/index.html1600=== CONT TestIsValidUploadKey/nix-cache-info1601=== CONT TestIsValidUploadKey/realisation_plus_in_output1602=== CONT TestIsValidUploadKey/realisation1603=== CONT TestIsValidUploadKey/unknown_type1604=== CONT TestIsValidUploadKey/build_log_equals1605=== CONT TestIsValidUploadKey/build_log_question_mark1606=== CONT TestIsValidUploadKey/build_log_plus_in_name1607=== CONT TestIsValidUploadKey/build_log_home-manager_file1608=== CONT TestIsValidUploadKey/build_log1609=== CONT TestIsValidUploadKey/listing1610=== CONT TestIsValidUploadKey/nar_plain1611=== CONT TestIsValidUploadKey/nar_xz1612=== CONT TestIsValidUploadKey/nar_zst1613--- PASS: TestIsValidUploadKey (0.09s)1614 --- PASS: TestIsValidUploadKey/narinfo (0.00s)1615 --- PASS: TestIsValidUploadKey/empty_key (0.00s)1616 --- PASS: TestIsValidUploadKey/absolute (0.00s)1617 --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1618 --- PASS: TestIsValidUploadKey/traversal (0.00s)1619 --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1620 --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1621 --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1622 --- PASS: TestIsValidUploadKey/index.html (0.00s)1623 --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1624 --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1625 --- PASS: TestIsValidUploadKey/realisation (0.00s)1626 --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1627 --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1628 --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1629 --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1630 --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1631 --- PASS: TestIsValidUploadKey/build_log (0.00s)1632 --- PASS: TestIsValidUploadKey/listing (0.00s)1633 --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1634 --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1635 --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1636=== CONT TestUploadHandlersRejectOversizedBody/create_pending_closure16372026/10/06 10:46:43 INFO Received uploads request method=POST path=/16382026/10/06 10:46:43 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:35769/oidc16392026/10/06 10:46:43 INFO lead: acquired remote=192.0.2.1:123416402026/10/06 10:46:43 INFO Starting cleanup of old closures method=DELETE path=/api/closures16412026/10/06 10:46:43 INFO Garbage collection started16422026/10/06 10:46:43 INFO Received push request method=POST path=/api/pushes16432026/10/06 10:46:43 INFO Aborted multipart uploads count=0 kept=016442026/10/06 10:46:43 WARN Force mode enabled - objects will be deleted immediately without grace period16452026/10/06 10:46:43 INFO Uploading 2 paths to 127.0.0.1 (1 already cached)16462026/10/06 10:46:43 INFO Uploading zvmsx05fyb10n3bapil2jfiyaannishk-b (208B)16472026/10/06 10:46:43 INFO Uploading 543zhrl5wz5vsn9zf97cpf1vsidgdlav-shared-dep (136B)16482026/10/06 10:46:43 WARN Failed to register uploaded object key=7vfzhyjjqbf7h5l51bi6dsyycc3b6gr2.ls error="server returned 404: 404 page not found\n"16492026/10/06 10:46:43 WARN Failed to register uploaded object key=zvmsx05fyb10n3bapil2jfiyaannishk.ls error="server returned 404: 404 page not found\n"16502026/10/06 10:46:43 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"16512026/10/06 10:46:43 WARN Failed to register uploaded object key=nar/1wvk9726jlgygw78c6wrscqw1px7zcviks03y7yxil211d5lxkk8.nar.zst error="server returned 404: 404 page not found\n"16522026/10/06 10:46:43 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign16532026/10/06 10:46:43 WARN Failed to register uploaded object key=543zhrl5wz5vsn9zf97cpf1vsidgdlav.ls error="server returned 404: 404 page not found\n"16542026/10/06 10:46:43 INFO Signed narinfos id=1 count=316552026/10/06 10:46:43 INFO Uploading 3 narinfos16562026/10/06 10:46:43 WARN Failed to register uploaded object key=7vfzhyjjqbf7h5l51bi6dsyycc3b6gr2.narinfo error="server returned 404: 404 page not found\n"16572026/10/06 10:46:43 WARN Failed to register uploaded object key=543zhrl5wz5vsn9zf97cpf1vsidgdlav.narinfo error="server returned 404: 404 page not found\n"16582026/10/06 10:46:43 INFO Received complete push request method=POST path=/api/pushes/1/complete16592026/10/06 10:46:43 WARN Failed to register uploaded object key=zvmsx05fyb10n3bapil2jfiyaannishk.narinfo error="server returned 404: 404 page not found\n"16602026/10/06 10:46:43 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=016612026/10/06 10:46:43 INFO Vacuumed table table=pending_closures16622026/10/06 10:46:43 INFO lead: acquired remote=192.0.2.1:123416632026/10/06 10:46:43 INFO lead: released remote=192.0.2.1:12341664--- PASS: TestLeadIncumbentAnnouncesAtOnce (0.50s)1665=== CONT TestUploadHandlersRejectOversizedBody/request_more_parts16662026/10/06 10:46:43 INFO Received request for more parts method=POST path=/16672026/10/06 10:46:43 INFO Upload complete. (94ms)1668=== NAME TestClientPushesUseOnePush1669 client_pushes_test.go:97: Retrieved narinfo from S3:1670 StorePath: /build/TestClientPushesUseOnePush28328551/001/store/543zhrl5wz5vsn9zf97cpf1vsidgdlav-shared-dep1671 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1672 Compression: zstd1673 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821674 NarSize: 1361675 References: 1676 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n16772026/10/06 10:46:43 INFO Vacuumed table table=pending_objects16782026/10/06 10:46:43 INFO Vacuumed table table=multipart_uploads1679 client_pushes_test.go:97: Retrieved narinfo from S3:1680 StorePath: /build/TestClientPushesUseOnePush28328551/001/store/7vfzhyjjqbf7h5l51bi6dsyycc3b6gr2-a1681 URL: nar/1wvk9726jlgygw78c6wrscqw1px7zcviks03y7yxil211d5lxkk8.nar.zst1682 Compression: zstd1683 NarHash: sha256:1wvk9726jlgygw78c6wrscqw1px7zcviks03y7yxil211d5lxkk81684 NarSize: 2081685 References: /build/TestClientPushesUseOnePush28328551/001/store/543zhrl5wz5vsn9zf97cpf1vsidgdlav-shared-dep1686 CA: text:sha256:1vq0g0a8q7psz35cg5gxa6334bq0jvp3vbdrdxncm2mffacl8q0s1687 client_pushes_test.go:97: Retrieved narinfo from S3:16882026/10/06 10:46:43 INFO Vacuumed table table=closures1689 StorePath: /build/TestClientPushesUseOnePush28328551/001/store/zvmsx05fyb10n3bapil2jfiyaannishk-b1690 URL: nar/1wvk9726jlgygw78c6wrscqw1px7zcviks03y7yxil211d5lxkk8.nar.zst1691 Compression: zstd1692 NarHash: sha256:1wvk9726jlgygw78c6wrscqw1px7zcviks03y7yxil211d5lxkk81693 NarSize: 2081694 References: /build/TestClientPushesUseOnePush28328551/001/store/543zhrl5wz5vsn9zf97cpf1vsidgdlav-shared-dep1695 CA: text:sha256:1vq0g0a8q7psz35cg5gxa6334bq0jvp3vbdrdxncm2mffacl8q0s16962026/10/06 10:46:43 INFO Vacuumed table table=objects1697--- PASS: TestClientPushesUseOnePush (0.78s)1698=== CONT TestUploadHandlersRejectOversizedBody/complete_multipart16992026/10/06 10:46:43 INFO Received complete multipart upload request method=POST path=/1700--- PASS: TestGCBugBareHashReferences (0.69s)1701=== CONT TestGCEndsOnShutdown/before_the_run17022026/10/06 10:46:43 INFO lead: acquired remote=192.0.2.1:12341703=== NAME TestPinProtectsFromGC1704 client_integration_test.go:731: Pinned store path: /build/TestPinProtectsFromGC3331618785/001/store/yf63hr2hs0vs7bdc64khlb8kr1d1np6j-pinned-file.txt1705 client_integration_test.go:732: Unpinned store path: /build/TestPinProtectsFromGC3331618785/001/store/y1skwp1yalx03znjiqbbm5yrx57wsf0a-unpinned-file.txt1706=== CONT TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete1707--- PASS: TestReadProxyNarStreaming (0.53s)1708=== CONT TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark1709=== CONT TestServerTLSConfig/no_client_CA1710=== CONT TestServerTLSConfig/not_a_PEM_file1711=== CONT TestServerTLSConfig/missing_CA_file1712--- PASS: TestServerTLSConfig (0.00s)1713 --- PASS: TestServerTLSConfig/no_client_CA (0.00s)1714 --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)1715 --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)1716=== CONT TestPush_RejectsBadRequests/no_roots17172026/10/06 10:46:43 INFO Received push request method=POST path=/api/pushes1718=== CONT TestPush_RejectsBadRequests/bad_root17192026/10/06 10:46:43 INFO Received push request method=POST path=/api/pushes1720=== CONT TestPush_RejectsBadRequests/root_not_in_objects17212026/10/06 10:46:43 INFO Received push request method=POST path=/api/pushes1722=== CONT TestPush_RejectsBadRequests/no_objects17232026/10/06 10:46:43 INFO Received push request method=POST path=/api/pushes1724=== CONT TestForceGCDuringPushOffersSweptObject/before_pending_rows1725--- PASS: TestPush_RejectsBadRequests (0.99s)1726 --- PASS: TestPush_RejectsBadRequests/no_roots (0.00s)1727 --- PASS: TestPush_RejectsBadRequests/bad_root (0.00s)1728 --- PASS: TestPush_RejectsBadRequests/root_not_in_objects (0.00s)1729 --- PASS: TestPush_RejectsBadRequests/no_objects (0.00s)1730--- PASS: TestReadProxy404 (0.51s)1731=== CONT TestForceGCDuringPushOffersSweptObject/after_presence_check17322026/10/06 10:46:43 INFO Received push request method=POST path=/api/pushes1733--- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.38s)1734=== CONT TestClientErrorHandling/InvalidStorePath17352026/10/06 10:46:43 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)17362026/10/06 10:46:43 INFO Uploading yf63hr2hs0vs7bdc64khlb8kr1d1np6j-pinned-file.txt (128B)17372026/10/06 10:46:43 WARN Failed to register uploaded object key=nar/0qmhsg42pqsz7x6hh02j8gznnac3yi3qpjhrqbwvz88i194jbb5s.nar.zst error="server returned 404: 404 page not found\n"17382026/10/06 10:46:43 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign17392026/10/06 10:46:43 WARN Failed to register uploaded object key=yf63hr2hs0vs7bdc64khlb8kr1d1np6j.ls error="server returned 404: 404 page not found\n"17402026/10/06 10:46:43 INFO Signed narinfos id=1 count=117412026/10/06 10:46:43 INFO Uploading 1 narinfos17422026/10/06 10:46:43 INFO Received complete push request method=POST path=/api/pushes/1/complete17432026/10/06 10:46:43 WARN Failed to register uploaded object key=yf63hr2hs0vs7bdc64khlb8kr1d1np6j.narinfo error="server returned 404: 404 page not found\n"17442026/10/06 10:46:43 INFO Upload complete. (69ms)1745--- PASS: TestService_ReadAuthMiddleware (0.39s)1746=== CONT TestClientErrorHandling/ServerNotAvailable1747--- PASS: TestReadProxyContentNegotiation (0.40s)1748=== CONT TestClientErrorHandling/InvalidAuthToken17492026/10/06 10:46:43 INFO Received push request method=POST path=/api/pushes17502026/10/06 10:46:43 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"17512026/10/06 10:46:43 WARN mTLS auth: bound subjects configured but subject DN unavailable17522026/10/06 10:46:43 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1753--- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.38s)1754=== CONT TestCacheConfigHandler/full_config,_no_issuer1755=== CONT TestCacheConfigHandler/no_signing_keys1756=== CONT TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1757=== CONT TestCacheConfigHandler/no_cache_url_configured1758--- PASS: TestCacheConfigHandler (0.00s)1759 --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)1760 --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)1761 --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)1762 --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)1763=== CONT TestResolveDBConnectionString/flag_wins1764=== CONT TestResolveDBConnectionString/PGHOST_allows_empty1765=== CONT TestResolveDBConnectionString/nothing_configured1766=== CONT TestResolveDBConnectionString/missing_file_is_an_error1767=== CONT TestResolveDBConnectionString/file_when_flag_empty1768=== CONT TestIsValidCachePath/narinfo1769=== CONT TestIsValidCachePath/index.html1770=== CONT TestIsValidCachePath/short_hash1771=== CONT TestIsValidCachePath/wrong_extension1772=== CONT TestIsValidCachePath/leading_slash17732026/10/06 10:46:43 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1774--- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.46s)17752026/10/06 10:46:43 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/present1776=== CONT TestIsValidCachePath/empty17772026/10/06 10:46:43 INFO Uploading y1skwp1yalx03znjiqbbm5yrx57wsf0a-unpinned-file.txt (128B)1778=== CONT TestIsValidCachePath/invalid_char_u1779=== CONT TestIsValidCachePath/invalid_char_e1780=== CONT TestIsValidCachePath/traversal_in_middle1781=== CONT TestIsValidCachePath/traversal_parent1782=== CONT TestIsValidCachePath/random_path1783=== CONT TestIsValidCachePath/nix-cache-info1784=== CONT TestIsValidCachePath/realisation1785=== CONT TestIsValidCachePath/log1786=== CONT TestIsValidCachePath/nar_xz1787--- PASS: TestResolveDBConnectionString (0.09s)1788 --- PASS: TestResolveDBConnectionString/flag_wins (0.00s)1789 --- PASS: TestResolveDBConnectionString/PGHOST_allows_empty (0.00s)1790 --- PASS: TestResolveDBConnectionString/nothing_configured (0.00s)1791 --- PASS: TestResolveDBConnectionString/missing_file_is_an_error (0.00s)1792 --- PASS: TestResolveDBConnectionString/file_when_flag_empty (0.00s)1793=== CONT TestIsValidCachePath/nar_uncompressed1794=== CONT TestIsValidCachePath/nar_bz21795=== CONT TestIsValidCachePath/nar_zst1796=== CONT TestIsValidCachePath/narinfo_all_nix_base32_chars1797=== CONT TestIsValidCachePath/ls1798--- PASS: TestIsValidCachePath (0.00s)1799 --- PASS: TestIsValidCachePath/narinfo (0.00s)1800 --- PASS: TestIsValidCachePath/index.html (0.00s)1801 --- PASS: TestIsValidCachePath/short_hash (0.00s)1802 --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1803 --- PASS: TestIsValidCachePath/leading_slash (0.00s)1804 --- PASS: TestIsValidCachePath/empty (0.00s)1805 --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1806 --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1807 --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1808 --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1809 --- PASS: TestIsValidCachePath/random_path (0.00s)1810 --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1811 --- PASS: TestIsValidCachePath/realisation (0.00s)1812 --- PASS: TestIsValidCachePath/log (0.00s)1813 --- PASS: TestIsValidCachePath/nar_xz (0.00s)1814 --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1815 --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1816 --- PASS: TestIsValidCachePath/nar_zst (0.00s)1817 --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)1818 --- PASS: TestIsValidCachePath/ls (0.00s)1819=== CONT TestService_RequireScope_OIDC/builder_may_write1820=== CONT TestService_RequireScope_OIDC/reader_may_read1821=== CONT TestService_RequireScope_OIDC/empty_netrc_password_may_not_read1822=== CONT TestService_RequireScope_OIDC/static_token_may_admin_with_netrc1823=== CONT TestService_RequireScope_OIDC/reader_may_read_with_netrc1824=== CONT TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read18252026/10/06 10:46:43 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]1826=== CONT TestService_RequireScope_OIDC/reader_may_not_write1827=== CONT TestService_RequireScope_OIDC/anonymous_may_not_read1828=== CONT TestService_RequireScope_OIDC/static_token_may_write1829=== CONT TestService_RequireScope_OIDC/writer_implies_read1830=== CONT TestService_RequireScope_OIDC/static_token_may_admin1831=== CONT TestService_RequireScope_OIDC/ops_may_admin1832=== CONT TestService_RequireScope_OIDC/ops_may_not_write1833=== CONT TestService_RequireScope_OIDC/builder_may_not_admin1834--- PASS: TestService_RequireScope_OIDC (0.55s)1835 --- PASS: TestService_RequireScope_OIDC/reader_may_read (0.00s)1836 --- PASS: TestService_RequireScope_OIDC/builder_may_write (0.00s)1837 --- PASS: TestService_RequireScope_OIDC/empty_netrc_password_may_not_read (0.00s)1838 --- PASS: TestService_RequireScope_OIDC/static_token_may_admin_with_netrc (0.00s)1839 --- PASS: TestService_RequireScope_OIDC/reader_may_read_with_netrc (0.00s)1840 --- PASS: TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read (0.00s)1841 --- PASS: TestService_RequireScope_OIDC/anonymous_may_not_read (0.00s)1842 --- PASS: TestService_RequireScope_OIDC/reader_may_not_write (0.00s)1843 --- PASS: TestService_RequireScope_OIDC/static_token_may_write (0.00s)1844 --- PASS: TestService_RequireScope_OIDC/static_token_may_admin (0.00s)1845 --- PASS: TestService_RequireScope_OIDC/writer_implies_read (0.00s)1846 --- PASS: TestService_RequireScope_OIDC/ops_may_admin (0.00s)1847 --- PASS: TestService_RequireScope_OIDC/ops_may_not_write (0.00s)1848 --- PASS: TestService_RequireScope_OIDC/builder_may_not_admin (0.00s)18492026/10/06 10:46:43 WARN Failed to register uploaded object key=y1skwp1yalx03znjiqbbm5yrx57wsf0a.ls error="server returned 404: 404 page not found\n"18502026/10/06 10:46:43 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign18512026/10/06 10:46:43 WARN Failed to register uploaded object key=nar/0gh0wdnr6gmk4r5sfzzh12isnl14rgmkrwy1ws41hpsrlndclg5h.nar.zst error="server returned 404: 404 page not found\n"18522026/10/06 10:46:43 INFO Signed narinfos id=2 count=118532026/10/06 10:46:43 INFO Uploading 1 narinfos18542026/10/06 10:46:43 INFO Received complete push request method=POST path=/api/pushes/2/complete18552026/10/06 10:46:43 WARN Failed to register uploaded object key=y1skwp1yalx03znjiqbbm5yrx57wsf0a.narinfo error="server returned 404: 404 page not found\n"18562026/10/06 10:46:43 INFO Upload complete. (149ms)18572026/10/06 10:46:43 INFO Received uploads request method=POST path=/api/pending_closures18582026/10/06 10:46:43 INFO Aborted multipart uploads count=0 kept=018592026/10/06 10:46:43 WARN Force mode enabled - objects will be deleted immediately without grace period18602026/10/06 10:46:43 INFO Starting cleanup of old closures method=DELETE path=/api/closures18612026/10/06 10:46:43 INFO Garbage collection started18622026/10/06 10:46:43 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=018632026/10/06 10:46:43 INFO Vacuumed table table=pending_closures18642026/10/06 10:46:43 INFO Vacuumed table table=pending_objects18652026/10/06 10:46:43 INFO Starting HTTP server address=/build/TestProxyHeadersOnlyTrustedOnSocket4248239087/001/proxy.sock18662026/10/06 10:46:43 INFO Vacuumed table table=multipart_uploads18672026/10/06 10:46:43 INFO Starting HTTP server address=127.0.0.1:3628718682026/10/06 10:46:43 INFO Vacuumed table table=closures18692026/10/06 10:46:43 WARN mTLS auth: subject not in bound subjects subject="CN=someone"18702026/10/06 10:46:43 INFO Shutdown signal received, draining in-flight requests timeout=10s1871--- PASS: TestProxyHeadersOnlyTrustedOnSocket (0.51s)18722026/10/06 10:46:43 INFO Vacuumed table table=objects18732026/10/06 10:46:43 INFO Aborted multipart uploads count=0 kept=018742026/10/06 10:46:43 WARN Force mode enabled - objects will be deleted immediately without grace period18752026/10/06 10:46:43 ERROR Failed to decode narinfo key=3hcdxyjf9yiq7qf3i4548drb6sjmwa1v.narinfo limit=16777216 error=<nil>1876--- PASS: TestReadProxyNarinfo (0.55s)18772026/10/06 10:46:43 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=01878=== RUN TestService_AuthMiddleware_OIDC/valid_OIDC_token18792026/10/06 10:46:43 INFO Vacuumed table table=pending_closures1880=== PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1881=== RUN TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1882=== PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1883=== RUN TestService_AuthMiddleware_OIDC/malformed_token_rejected1884=== PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1885=== RUN TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1886=== PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1887=== CONT TestService_AuthMiddleware_OIDC/valid_OIDC_token1888=== CONT TestService_AuthMiddleware_OIDC/malformed_token_rejected1889=== CONT TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured18902026/10/06 10:46:43 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]1891=== CONT TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected18922026/10/06 10:46:43 INFO Vacuumed table table=pending_objects18932026/10/06 10:46:43 INFO Vacuumed table table=multipart_uploads18942026/10/06 10:46:43 INFO Vacuumed table table=closures18952026/10/06 10:46:43 WARN Authentication failed token_preview=eyJhbGciOi...wEtdJiddNg token_length=701 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]18962026/10/06 10:46:43 INFO Vacuumed table table=objects1897--- PASS: TestService_AuthMiddleware_OIDC (0.59s)1898 --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1899 --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1900 --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1901 --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)19022026/10/06 10:46:43 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=192.953827ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19032026/10/06 10:46:43 INFO Received uploads request method=POST path=/api/pending_closures19042026/10/06 10:46:43 INFO Aborted multipart uploads count=0 kept=019052026/10/06 10:46:43 WARN Force mode enabled - objects will be deleted immediately without grace period19062026/10/06 10:46:43 ERROR failed to remove object object=nar/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.nar.zst error="Post \"http://localhost:39827/bucket88/?delete=\": context canceled"19072026/10/06 10:46:43 INFO Aborted multipart uploads count=0 kept=019082026/10/06 10:46:43 WARN Force mode enabled - objects will be deleted immediately without grace period19092026/10/06 10:46:43 INFO Received uploads request method=POST path=/api/pending_closures1910--- PASS: TestGCEndsOnShutdown (0.00s)1911 --- PASS: TestGCEndsOnShutdown/before_the_run (0.44s)1912 --- PASS: TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete (0.45s)1913 --- PASS: TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark (0.48s)19142026/10/06 10:46:43 INFO Aborted multipart uploads count=0 kept=019152026/10/06 10:46:43 WARN Force mode enabled - objects will be deleted immediately without grace period19162026/10/06 10:46:43 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=01917=== NAME TestOrphanedObjectsGCStressTest1918 orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains19192026/10/06 10:46:43 INFO Vacuumed table table=pending_closures19202026/10/06 10:46:43 INFO Vacuumed table table=pending_objects19212026/10/06 10:46:43 INFO Vacuumed table table=multipart_uploads19222026/10/06 10:46:43 INFO Vacuumed table table=closures19232026/10/06 10:46:43 INFO Vacuumed table table=objects1924--- PASS: TestForceGCDuringPushOffersSweptObject (0.00s)1925 --- PASS: TestForceGCDuringPushOffersSweptObject/before_pending_rows (0.32s)1926 --- PASS: TestForceGCDuringPushOffersSweptObject/after_presence_check (0.46s)1927=== NAME TestOrphanedObjectsGCStressTest1928 orphaned_objects_gc_test.go:446: Marked 210 objects for deletion19292026/10/06 10:46:43 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=438.681467ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19302026/10/06 10:46:43 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1931--- PASS: TestUploadHandlersRejectOversizedBody (0.16s)1932 --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.05s)1933 --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.06s)1934 --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.81s)19352026/10/06 10:46:43 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"1936=== NAME TestOrphanedObjectsGCStressTest1937 orphaned_objects_gc_test.go:509: Stress test completed successfully:1938 orphaned_objects_gc_test.go:510: - Active objects preserved: 201939 orphaned_objects_gc_test.go:511: - Objects deleted: 2101940 orphaned_objects_gc_test.go:512: - Total GC'd: 2101941--- PASS: TestOrphanedObjectsGCStressTest (2.99s)19422026/10/06 10:46:44 INFO lead: released remote=192.0.2.1:12341943--- PASS: TestLeadEndsOnShutdown (1.54s)19442026/10/06 10:46:44 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=856.154965ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19452026/10/06 10:46:44 INFO lead: released remote=192.0.2.1:12341946--- PASS: TestPendingClosureWriteTimeout (0.09s)1947 --- PASS: TestPendingClosureWriteTimeout/empty (0.00s)1948 --- PASS: TestPendingClosureWriteTimeout/670k_objects (0.00s)1949 --- PASS: TestPendingClosureWriteTimeout/400_objects (0.00s)1950 --- PASS: TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline (1.34s)19512026/10/06 10:46:44 INFO lead: acquired remote=192.0.2.1:123419522026/10/06 10:46:45 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.697500889s error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19532026/10/06 10:46:45 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=3 objects_failed=01954=== NAME TestClientIntegration1955 client_integration_test.go:323: Objects in database after GC:1956 client_integration_test.go:323: Successfully deleted all objects with GC --force1957--- PASS: TestClientIntegration (2.75s)19582026/10/06 10:46:45 WARN Rate limiter enabled after throttle name=s3-test rate=519592026/10/06 10:46:45 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1960=== NAME TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1961 throttle_test.go:213: Proxy stats: total=13, throttled=10, completeMultipart=101962 throttle_test.go:215: Rate limiter: enabled=true, rate=5.001963--- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (4.84s)19642026/10/06 10:46:45 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=3 objects_failed=019652026/10/06 10:46:45 INFO Received create pin request method=POST path=/api/pins/myapp19662026/10/06 10:46:45 INFO lead: released remote=192.0.2.1:12341967--- PASS: TestLeadElectsOneAndHandsOver (2.84s)19682026/10/06 10:46:45 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC3331618785/001/store/yf63hr2hs0vs7bdc64khlb8kr1d1np6j-pinned-file.txt narinfo_key=yf63hr2hs0vs7bdc64khlb8kr1d1np6j.narinfo19692026/10/06 10:46:45 INFO Starting cleanup of old closures method=DELETE path=/api/closures19702026/10/06 10:46:45 INFO Garbage collection started19712026/10/06 10:46:45 INFO Aborted multipart uploads count=0 kept=019722026/10/06 10:46:45 WARN Force mode enabled - objects will be deleted immediately without grace period19732026/10/06 10:46: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=019742026/10/06 10:46:45 INFO Vacuumed table table=pending_closures19752026/10/06 10:46:45 INFO Vacuumed table table=pending_objects19762026/10/06 10:46:45 INFO Vacuumed table table=multipart_uploads19772026/10/06 10:46:45 INFO Vacuumed table table=closures19782026/10/06 10:46:45 INFO Vacuumed table table=objects1979--- PASS: TestReadProxyOutlastsServerWriteTimeout (4.68s)19802026/10/06 10:46:46 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-config19812026/10/06 10:46:46 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=185.264828ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19822026/10/06 10:46:47 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=372.211455ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19832026/10/06 10:46:47 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=755.142265ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config1984=== NAME TestPinProtectsFromGC1985 client_integration_test.go:840: Pin successfully protected closure from garbage collection1986--- PASS: TestPinProtectsFromGC (4.95s)19872026/10/06 10:46:48 INFO Aborted multipart uploads count=1 kept=019882026/10/06 10:46:48 INFO Received cleanup request method=DELETE path=/api/pending_closures19892026/10/06 10:46:48 INFO Aborted multipart uploads count=1 kept=01990--- PASS: TestPendingCleanupUsesOneCutoff (6.68s)1991--- PASS: TestConnectWaitsForAPeerMigration (5.20s)19922026/10/06 10:46:48 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.50053074s error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19932026/10/06 10:46:49 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"19942026/10/06 10:46:49 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-config19952026/10/06 10:46:49 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=185.520365ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19962026/10/06 10:46:50 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=398.508336ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19972026/10/06 10:46:50 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=797.329326ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19982026/10/06 10:46:51 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.504436584s error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19992026/10/06 10:46:52 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_closures20002026/10/06 10:46:52 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=205.95119ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures20012026/10/06 10:46:53 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=425.826997ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures20022026/10/06 10:46:53 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=818.569518ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures20032026/10/06 10:46:54 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.500676749s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures2004--- PASS: TestClientErrorHandling (0.00s)2005 --- PASS: TestClientErrorHandling/InvalidStorePath (0.47s)2006 --- PASS: TestClientErrorHandling/InvalidAuthToken (0.53s)2007 --- PASS: TestClientErrorHandling/ServerNotAvailable (12.46s)2008PASS20092026-10-06 10:46:56.203 UTC [128] LOG: received smart shutdown request20102026-10-06 10:46:56.208 UTC [128] LOG: background worker "logical replication launcher" (PID 138) exited with exit code 120112026-10-06 10:46:56.220 UTC [133] LOG: shutting down20122026-10-06 10:46:56.221 UTC [133] LOG: checkpoint starting: shutdown immediate20132026-10-06 10:46:58.175 UTC [133] LOG: checkpoint complete: wrote 11262 buffers (68.7%), wrote 5 SLRU buffers; 0 WAL file(s) added, 0 removed, 26 recycled; write=0.263 s, sync=1.638 s, total=1.955 s; sync files=31336, longest=0.002 s, average=0.001 s; distance=433097 kB, estimate=433097 kB; lsn=0/1BE58408, redo lsn=0/1BE5840820142026-10-06 10:46:58.257 UTC [128] LOG: database system is shut down2015Running OIDC tests...2016=== RUN TestAudienceForIssuer2017=== PAUSE TestAudienceForIssuer2018=== RUN TestGlobMatch2019=== PAUSE TestGlobMatch2020=== RUN TestValidateToken_ValidToken2021=== PAUSE TestValidateToken_ValidToken2022=== RUN TestValidateToken_WrongAudience2023=== PAUSE TestValidateToken_WrongAudience2024=== RUN TestValidateToken_Expired2025=== PAUSE TestValidateToken_Expired2026=== RUN TestValidateToken_BoundClaimsMismatch2027=== PAUSE TestValidateToken_BoundClaimsMismatch2028=== RUN TestValidateToken_BoundSubjectMismatch2029=== PAUSE TestValidateToken_BoundSubjectMismatch2030=== RUN TestValidateToken_MultipleProviders2031=== PAUSE TestValidateToken_MultipleProviders2032=== RUN TestValidateToken_NoMatchingProvider2033=== PAUSE TestValidateToken_NoMatchingProvider2034=== RUN TestValidateToken_KubernetesServiceAccount2035=== PAUSE TestValidateToken_KubernetesServiceAccount2036=== RUN TestNewValidator_KubernetesRequiresCA2037=== PAUSE TestNewValidator_KubernetesRequiresCA2038=== RUN TestValidateToken_KubernetesIssuerFromOwnToken2039=== PAUSE TestValidateToken_KubernetesIssuerFromOwnToken2040=== RUN TestPins_ReservedForMatchingRule2041=== PAUSE TestPins_ReservedForMatchingRule2042=== RUN TestPins_TopLevelShorthand2043=== PAUSE TestPins_TopLevelShorthand2044=== RUN TestPins_ConfigValidation2045=== PAUSE TestPins_ConfigValidation2046=== RUN TestScopes_LegacyProviderDefaultsToWrite2047=== PAUSE TestScopes_LegacyProviderDefaultsToWrite2048=== RUN TestScopes_Rules2049=== PAUSE TestScopes_Rules2050=== RUN TestScopes_ConfigValidation2051=== PAUSE TestScopes_ConfigValidation2052=== CONT TestAudienceForIssuer2053=== CONT TestValidateToken_KubernetesServiceAccount2054--- PASS: TestAudienceForIssuer (0.00s)2055=== CONT TestValidateToken_ValidToken2056=== CONT TestGlobMatch2057=== CONT TestPins_ReservedForMatchingRule2058=== CONT TestPins_TopLevelShorthand2059=== CONT TestValidateToken_MultipleProviders2060=== CONT TestValidateToken_NoMatchingProvider2061=== CONT TestValidateToken_BoundSubjectMismatch2062=== CONT TestValidateToken_KubernetesIssuerFromOwnToken2063=== CONT TestValidateToken_Expired2064=== CONT TestScopes_Rules2065=== CONT TestScopes_ConfigValidation2066=== CONT TestNewValidator_KubernetesRequiresCA2067=== CONT TestScopes_LegacyProviderDefaultsToWrite2068=== CONT TestValidateToken_BoundClaimsMismatch2069--- PASS: TestScopes_ConfigValidation (0.00s)2070=== CONT TestValidateToken_WrongAudience2071=== CONT TestPins_ConfigValidation2072=== RUN TestGlobMatch/foo_foo2073=== PAUSE TestGlobMatch/foo_foo2074=== RUN TestGlobMatch/foo_bar2075=== PAUSE TestGlobMatch/foo_bar2076=== RUN TestGlobMatch/*_2077=== PAUSE TestGlobMatch/*_2078=== RUN TestGlobMatch/*_anything2079=== PAUSE TestGlobMatch/*_anything2080=== RUN TestGlobMatch/foo*_foo2081=== PAUSE TestGlobMatch/foo*_foo2082=== RUN TestGlobMatch/foo*_foobar2083=== PAUSE TestGlobMatch/foo*_foobar2084=== RUN TestGlobMatch/foo*_bar2085=== PAUSE TestGlobMatch/foo*_bar2086=== RUN TestGlobMatch/*bar_bar2087=== PAUSE TestGlobMatch/*bar_bar2088=== RUN TestGlobMatch/*bar_foobar2089=== PAUSE TestGlobMatch/*bar_foobar2090=== RUN TestGlobMatch/*bar_foo2091=== PAUSE TestGlobMatch/*bar_foo2092=== RUN TestGlobMatch/foo*bar_foobar2093=== PAUSE TestGlobMatch/foo*bar_foobar2094=== RUN TestGlobMatch/foo*bar_foo123bar2095=== PAUSE TestGlobMatch/foo*bar_foo123bar2096=== RUN TestGlobMatch/foo*bar_foobarbaz2097=== PAUSE TestGlobMatch/foo*bar_foobarbaz2098=== RUN TestGlobMatch/*/*_foo/bar2099=== PAUSE TestGlobMatch/*/*_foo/bar2100=== RUN TestGlobMatch/*/*_foo2101=== PAUSE TestGlobMatch/*/*_foo2102=== RUN TestGlobMatch/refs/heads/*_refs/heads/main2103=== PAUSE TestGlobMatch/refs/heads/*_refs/heads/main2104=== RUN TestGlobMatch/refs/heads/*_refs/tags/v1.02105=== PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.02106=== RUN TestGlobMatch/refs/*/main_refs/heads/main2107=== PAUSE TestGlobMatch/refs/*/main_refs/heads/main2108=== RUN TestGlobMatch/fo?_foo2109=== PAUSE TestGlobMatch/fo?_foo2110=== RUN TestGlobMatch/fo?_fo2111=== PAUSE TestGlobMatch/fo?_fo2112=== RUN TestGlobMatch/fo?_fooo2113=== PAUSE TestGlobMatch/fo?_fooo2114=== RUN TestGlobMatch/?oo_foo2115=== PAUSE TestGlobMatch/?oo_foo2116=== RUN TestGlobMatch/?oo_boo2117=== PAUSE TestGlobMatch/?oo_boo2118=== RUN TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2119=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2120=== RUN TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2121=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2122=== CONT TestGlobMatch/foo_foo2123=== CONT TestGlobMatch/foo*bar_foobarbaz2124=== CONT TestGlobMatch/foo*bar_foo123bar2125=== CONT TestGlobMatch/foo*bar_foobar2126=== CONT TestGlobMatch/*bar_foo2127=== CONT TestGlobMatch/*bar_foobar2128=== CONT TestGlobMatch/*bar_bar2129=== CONT TestGlobMatch/foo*_bar2130=== CONT TestGlobMatch/foo*_foobar2131=== CONT TestGlobMatch/foo*_foo2132=== CONT TestGlobMatch/*_anything2133=== CONT TestGlobMatch/*_2134=== CONT TestGlobMatch/foo_bar2135=== CONT TestGlobMatch/fo?_fo2136=== CONT TestGlobMatch/refs/heads/*_refs/heads/main2137--- PASS: TestPins_ConfigValidation (0.00s)2138=== CONT TestGlobMatch/fo?_fooo2139=== CONT TestGlobMatch/*/*_foo2140=== CONT TestGlobMatch/*/*_foo/bar2141=== CONT TestGlobMatch/refs/*/main_refs/heads/main2142=== CONT TestGlobMatch/refs/heads/*_refs/tags/v1.02143=== CONT TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2144=== CONT TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2145=== CONT TestGlobMatch/?oo_boo2146=== CONT TestGlobMatch/?oo_foo2147=== CONT TestGlobMatch/fo?_foo2148--- PASS: TestGlobMatch (0.01s)2149 --- PASS: TestGlobMatch/foo_foo (0.00s)2150 --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)2151 --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)2152 --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)2153 --- PASS: TestGlobMatch/*bar_foo (0.00s)2154 --- PASS: TestGlobMatch/*bar_foobar (0.00s)2155 --- PASS: TestGlobMatch/*bar_bar (0.00s)2156 --- PASS: TestGlobMatch/foo*_bar (0.00s)2157 --- PASS: TestGlobMatch/foo*_foobar (0.00s)2158 --- PASS: TestGlobMatch/foo*_foo (0.00s)2159 --- PASS: TestGlobMatch/*_anything (0.00s)2160 --- PASS: TestGlobMatch/*_ (0.00s)2161 --- PASS: TestGlobMatch/foo_bar (0.00s)2162 --- PASS: TestGlobMatch/fo?_fo (0.00s)2163 --- PASS: TestGlobMatch/fo?_fooo (0.00s)2164 --- PASS: TestGlobMatch/*/*_foo (0.00s)2165 --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)2166 --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)2167 --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)2168 --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)2169 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)2170 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)2171 --- PASS: TestGlobMatch/?oo_boo (0.00s)2172 --- PASS: TestGlobMatch/fo?_foo (0.00s)2173 --- PASS: TestGlobMatch/?oo_foo (0.00s)21742026/10/06 10:47:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:36343/oidc21752026/10/06 10:47:00 INFO OIDC provider initialized name=provider1 issuer=http://127.0.0.1:33065/oidc2176--- PASS: TestValidateToken_BoundClaimsMismatch (0.03s)21772026/10/06 10:47:00 INFO OIDC provider initialized name=provider2 issuer=http://127.0.0.1:41379/oidc2178--- PASS: TestValidateToken_MultipleProviders (0.04s)21792026/10/06 10:47:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:35385/oidc2180--- PASS: TestScopes_LegacyProviderDefaultsToWrite (0.04s)21812026/10/06 10:47:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:41579/oidc2182--- PASS: TestValidateToken_BoundSubjectMismatch (0.05s)21832026/10/06 10:47:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:46799/oidc2184--- PASS: TestPins_TopLevelShorthand (0.07s)21852026/10/06 10:47:00 http: TLS handshake error from 127.0.0.1:45490: remote error: tls: bad certificate2186--- PASS: TestNewValidator_KubernetesRequiresCA (0.07s)21872026/10/06 10:47:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:34901/oidc21882026/10/06 10:47:00 INFO OIDC provider initialized name=kubernetes issuer=https://oidc.eks.invalid/id/ABC1232189--- PASS: TestPins_ReservedForMatchingRule (0.09s)2190--- PASS: TestValidateToken_KubernetesIssuerFromOwnToken (0.09s)21912026/10/06 10:47:00 INFO OIDC provider initialized name=provider1 issuer=http://127.0.0.1:39579/oidc2192--- PASS: TestValidateToken_NoMatchingProvider (0.11s)21932026/10/06 10:47:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:34523/oidc21942026/10/06 10:47:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:40111/oidc2195--- PASS: TestValidateToken_ValidToken (0.12s)2196--- PASS: TestValidateToken_WrongAudience (0.11s)21972026/10/06 10:47:00 INFO OIDC provider initialized name=kubernetes issuer=https://127.0.0.1:437452198--- PASS: TestValidateToken_KubernetesServiceAccount (0.16s)21992026/10/06 10:47:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:39807/oidc2200--- PASS: TestValidateToken_Expired (0.17s)22012026/10/06 10:47:00 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:43205/oidc2202--- PASS: TestScopes_Rules (0.24s)2203PASS2204Running hook tests...2205=== RUN TestSendPathsEmpty2206=== PAUSE TestSendPathsEmpty2207=== RUN TestQueueEnqueueAndFetch2208=== PAUSE TestQueueEnqueueAndFetch2209=== RUN TestQueueDeduplication2210=== PAUSE TestQueueDeduplication2211=== RUN TestQueueRemove2212=== PAUSE TestQueueRemove2213=== RUN TestQueueFetchBatchLimit2214=== PAUSE TestQueueFetchBatchLimit2215=== RUN TestQueueRetryMovesToBack2216=== PAUSE TestQueueRetryMovesToBack2217=== RUN TestQueueFetchRemoveLifecycle2218=== PAUSE TestQueueFetchRemoveLifecycle2219=== RUN TestQueueConcurrentWriters2220=== PAUSE TestQueueConcurrentWriters2221=== RUN TestQueueRemoveLargeClosure2222=== PAUSE TestQueueRemoveLargeClosure2223=== RUN TestServerClientIntegration2224=== PAUSE TestServerClientIntegration2225=== RUN TestServerQueueError2226=== PAUSE TestServerQueueError2227=== RUN TestGetListenerSocketActivation2228 server_test.go:210: === RUN TestGetListenerSocketActivation2229 --- PASS: TestGetListenerSocketActivation (0.00s)2230 PASS2231 2232--- PASS: TestGetListenerSocketActivation (0.01s)2233=== RUN TestDrainIsolatesPoisonPath2234=== PAUSE TestDrainIsolatesPoisonPath2235=== RUN TestRunNotBlockedByPoisonHead2236=== PAUSE TestRunNotBlockedByPoisonHead2237=== RUN TestDrainGivesUpWhenServerDown2238=== PAUSE TestDrainGivesUpWhenServerDown2239=== RUN TestFailedPathPrunedByLaterClosure2240=== PAUSE TestFailedPathPrunedByLaterClosure2241=== RUN TestWorkerUploadsAndRemoves2242=== PAUSE TestWorkerUploadsAndRemoves2243=== RUN TestWorkerSkipsGCdPaths2244=== PAUSE TestWorkerSkipsGCdPaths2245=== RUN TestWorkerPrunesClosureDeps2246=== PAUSE TestWorkerPrunesClosureDeps2247=== RUN TestDrainTimeout2248=== PAUSE TestDrainTimeout2249=== CONT TestSendPathsEmpty2250=== CONT TestServerQueueError2251--- PASS: TestSendPathsEmpty (0.00s)2252=== CONT TestQueueFetchBatchLimit2253=== CONT TestQueueRemove2254=== CONT TestQueueDeduplication2255=== CONT TestQueueEnqueueAndFetch2256=== CONT TestQueueRetryMovesToBack2257=== CONT TestQueueRemoveLargeClosure2258=== CONT TestQueueConcurrentWriters22592026/10/06 10:47:00 ERROR Failed to queue paths error="permission denied" count=12260=== CONT TestServerClientIntegration2261=== CONT TestWorkerUploadsAndRemoves2262=== CONT TestQueueFetchRemoveLifecycle2263=== CONT TestDrainTimeout2264=== CONT TestWorkerSkipsGCdPaths2265=== CONT TestWorkerPrunesClosureDeps2266=== CONT TestRunNotBlockedByPoisonHead2267=== CONT TestDrainIsolatesPoisonPath2268=== CONT TestFailedPathPrunedByLaterClosure2269=== CONT TestDrainGivesUpWhenServerDown2270--- PASS: TestServerQueueError (0.00s)2271--- PASS: TestServerClientIntegration (0.00s)22722026/10/06 10:47:00 INFO Uploading batch count=122732026/10/06 10:47:00 ERROR Upload failed error="upload failed" count=122742026/10/06 10:47:00 INFO Upload queue status pending=222752026/10/06 10:47:00 INFO Upload queue status pending=222762026/10/06 10:47:00 INFO Uploading batch count=222772026/10/06 10:47:00 ERROR Upload failed error="upload failed" count=222782026/10/06 10:47:00 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown51504253/002/a22792026/10/06 10:47:00 INFO Uploading batch count=222802026/10/06 10:47:00 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths3335547943/002/nonexistent22812026/10/06 10:47:00 INFO Uploading batch count=422822026/10/06 10:47:00 ERROR Upload failed error="upload failed" count=422832026/10/06 10:47:00 INFO Upload queue status pending=222842026/10/06 10:47:00 INFO Upload queue status pending=322852026/10/06 10:47:00 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown51504253/002/b2286--- PASS: TestQueueEnqueueAndFetch (0.02s)22872026/10/06 10:47:00 INFO Uploading batch count=22288--- PASS: TestQueueFetchBatchLimit (0.02s)22892026/10/06 10:47:00 INFO Uploading batch count=122902026/10/06 10:47:00 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainIsolatesPoisonPath3277134388/002/bbb22912026/10/06 10:47:00 INFO Uploading batch count=122922026/10/06 10:47:00 INFO Uploading batch count=12293--- PASS: TestQueueRemove (0.02s)22942026/10/06 10:47:00 INFO Uploading batch count=122952026/10/06 10:47:00 ERROR Upload failed error="upload failed" count=12296--- PASS: TestQueueDeduplication (0.02s)22972026/10/06 10:47:00 INFO Uploading batch count=222982026/10/06 10:47:00 ERROR Upload failed error="upload failed" count=222992026/10/06 10:47:00 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown51504253/002/c23002026/10/06 10:47:00 INFO Uploading batch count=12301--- PASS: TestQueueFetchRemoveLifecycle (0.02s)2302--- PASS: TestQueueRetryMovesToBack (0.02s)23032026/10/06 10:47:00 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown51504253/002/d23042026/10/06 10:47:00 INFO Uploading batch count=123052026/10/06 10:47:00 ERROR Upload failed error="upload failed" count=123062026/10/06 10:47:00 INFO Uploading batch count=223072026/10/06 10:47:00 INFO Uploading batch count=123082026/10/06 10:47:00 ERROR Upload failed error="upload failed" count=123092026/10/06 10:47:00 ERROR Upload failed error="upload failed" count=223102026/10/06 10:47:00 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown51504253/002/e23112026/10/06 10:47:00 INFO Uploading batch count=123122026/10/06 10:47:00 ERROR Upload failed error="upload failed" count=12313--- PASS: TestFailedPathPrunedByLaterClosure (0.02s)23142026/10/06 10:47:00 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown51504253/002/f23152026/10/06 10:47:00 ERROR Drain finished with paths left in queue remaining=123162026/10/06 10:47:00 ERROR Drain finished with paths left in queue remaining=102317--- PASS: TestDrainIsolatesPoisonPath (0.02s)2318--- PASS: TestDrainGivesUpWhenServerDown (0.02s)2319--- PASS: TestWorkerSkipsGCdPaths (0.03s)2320--- PASS: TestWorkerUploadsAndRemoves (0.03s)2321--- PASS: TestWorkerPrunesClosureDeps (0.03s)2322--- PASS: TestQueueRemoveLargeClosure (0.06s)23232026/10/06 10:47:00 ERROR Upload failed error="context deadline exceeded" count=223242026/10/06 10:47:00 ERROR Drain finished with paths left in queue remaining=42325--- PASS: TestDrainTimeout (0.22s)2326--- PASS: TestQueueConcurrentWriters (0.38s)23272026/10/06 10:47:01 INFO Uploading batch count=123282026/10/06 10:47:01 INFO Uploading batch count=123292026/10/06 10:47:01 INFO Uploading batch count=123302026/10/06 10:47:01 ERROR Upload failed error="upload failed" count=123312026/10/06 10:47:01 INFO Uploading batch count=123322026/10/06 10:47:01 ERROR Upload failed error="upload failed" count=123332026/10/06 10:47:01 INFO Uploading batch count=123342026/10/06 10:47:01 ERROR Upload failed error="upload failed" count=123352026/10/06 10:47:01 INFO Uploading batch count=123362026/10/06 10:47:01 ERROR Upload failed error="upload failed" count=123372026/10/06 10:47:01 ERROR Drain finished with paths left in queue remaining=12338--- PASS: TestRunNotBlockedByPoisonHead (1.04s)2339PASS2340Running signing tests...2341=== RUN TestExternalSigner2342=== PAUSE TestExternalSigner2343=== RUN TestExternalSignerWrongPublicKey2344=== PAUSE TestExternalSignerWrongPublicKey2345=== RUN TestExternalSignerErrors2346=== PAUSE TestExternalSignerErrors2347=== RUN TestExternalSignerRestartsAfterCrash2348=== PAUSE TestExternalSignerRestartsAfterCrash2349=== RUN TestExternalSignerProgramErrorKeepsProcess2350=== PAUSE TestExternalSignerProgramErrorKeepsProcess2351=== RUN TestExternalSignerClose2352=== PAUSE TestExternalSignerClose2353=== RUN TestExternalSignerRejectsInvalidPublicKey2354=== PAUSE TestExternalSignerRejectsInvalidPublicKey2355=== RUN TestExternalSignerCancellation2356=== PAUSE TestExternalSignerCancellation2357=== RUN TestGenerateFingerprint2358=== PAUSE TestGenerateFingerprint2359=== RUN TestParseSigningKey2360=== PAUSE TestParseSigningKey2361=== RUN TestSign2362=== PAUSE TestSign2363=== RUN TestSignBatch2364=== PAUSE TestSignBatch2365=== RUN TestNilKeyPublicKey2366=== PAUSE TestNilKeyPublicKey2367=== RUN TestSignErrors2368=== PAUSE TestSignErrors2369=== CONT TestExternalSigner2370=== CONT TestSignBatch2371=== CONT TestExternalSignerProgramErrorKeepsProcess2372=== CONT TestExternalSignerCancellation2373=== CONT TestSign2374=== CONT TestParseSigningKey2375=== CONT TestGenerateFingerprint2376--- PASS: TestSign (0.00s)2377=== CONT TestExternalSignerErrors2378=== CONT TestExternalSignerRestartsAfterCrash2379=== CONT TestExternalSignerWrongPublicKey2380=== CONT TestSignErrors2381=== CONT TestExternalSignerRejectsInvalidPublicKey2382=== CONT TestExternalSignerClose2383=== CONT TestNilKeyPublicKey2384=== RUN TestParseSigningKey/valid_32-byte_key2385=== RUN TestExternalSignerErrors/too_few_signatures2386=== PAUSE TestExternalSignerErrors/too_few_signatures2387=== RUN TestExternalSignerErrors/missing_signature_name2388=== PAUSE TestExternalSignerErrors/missing_signature_name2389=== RUN TestExternalSignerErrors/wrong_signature_name2390=== PAUSE TestExternalSignerErrors/wrong_signature_name2391=== RUN TestSignErrors/nil_key2392=== PAUSE TestSignErrors/nil_key2393=== RUN TestGenerateFingerprint/basic_with_references2394=== PAUSE TestParseSigningKey/valid_32-byte_key2395=== RUN TestExternalSignerErrors/invalid_signature_base642396=== PAUSE TestExternalSignerErrors/invalid_signature_base642397--- PASS: TestSignBatch (0.00s)2398=== RUN TestExternalSignerRejectsInvalidPublicKey/#002399=== RUN TestSignErrors/nil_narinfo2400=== PAUSE TestSignErrors/nil_narinfo2401=== RUN TestSignErrors/invalid_narinfo2402=== RUN TestExternalSignerErrors/wrong_shape2403--- PASS: TestNilKeyPublicKey (0.00s)2404=== PAUSE TestExternalSignerRejectsInvalidPublicKey/#002405=== RUN TestExternalSignerRejectsInvalidPublicKey/no-colon2406=== PAUSE TestExternalSignerRejectsInvalidPublicKey/no-colon2407=== RUN TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=2408=== PAUSE TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=2409=== RUN TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!2410=== PAUSE TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!2411=== RUN TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==2412=== RUN TestParseSigningKey/valid_32-byte_key_with_different_name2413=== PAUSE TestParseSigningKey/valid_32-byte_key_with_different_name2414=== PAUSE TestGenerateFingerprint/basic_with_references2415=== PAUSE TestSignErrors/invalid_narinfo2416=== RUN TestSignErrors/canceled2417=== PAUSE TestSignErrors/canceled2418=== CONT TestSignErrors/nil_key2419=== CONT TestSignErrors/nil_narinfo2420=== CONT TestSignErrors/invalid_narinfo2421=== PAUSE TestExternalSignerErrors/wrong_shape2422=== RUN TestExternalSignerErrors/wrong_id2423=== PAUSE TestExternalSignerErrors/wrong_id2424=== RUN TestExternalSignerErrors/unrequested_key2425=== PAUSE TestExternalSignerErrors/unrequested_key2426=== RUN TestExternalSignerErrors/program_error2427=== PAUSE TestExternalSignerErrors/program_error2428=== RUN TestExternalSignerErrors/invalid_JSON2429=== CONT TestSignErrors/canceled2430=== PAUSE TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==2431=== RUN TestParseSigningKey/no_colon2432=== PAUSE TestParseSigningKey/no_colon2433=== RUN TestGenerateFingerprint/no_references2434--- PASS: TestExternalSignerProgramErrorKeepsProcess (0.00s)2435--- PASS: TestExternalSignerClose (0.00s)2436--- PASS: TestSignErrors (0.00s)2437 --- PASS: TestSignErrors/nil_key (0.00s)2438 --- PASS: TestSignErrors/nil_narinfo (0.00s)2439 --- PASS: TestSignErrors/invalid_narinfo (0.00s)2440 --- PASS: TestSignErrors/canceled (0.00s)2441=== PAUSE TestGenerateFingerprint/no_references2442=== CONT TestExternalSignerRejectsInvalidPublicKey/#002443=== CONT TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=2444=== CONT TestExternalSignerRejectsInvalidPublicKey/no-colon2445=== CONT TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!2446=== CONT TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==2447--- PASS: TestExternalSignerRejectsInvalidPublicKey (0.00s)2448 --- PASS: TestExternalSignerRejectsInvalidPublicKey/#00 (0.00s)2449 --- PASS: TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA= (0.00s)2450 --- PASS: TestExternalSignerRejectsInvalidPublicKey/no-colon (0.00s)2451 --- PASS: TestExternalSignerRejectsInvalidPublicKey/test-key:invalid! (0.00s)2452 --- PASS: TestExternalSignerRejectsInvalidPublicKey/test-key:YQ== (0.00s)2453=== PAUSE TestExternalSignerErrors/invalid_JSON2454=== RUN TestExternalSignerErrors/wrong_signatures_shape2455=== PAUSE TestExternalSignerErrors/wrong_signatures_shape2456=== RUN TestExternalSignerErrors/wrong_signature_length2457=== PAUSE TestExternalSignerErrors/wrong_signature_length2458=== RUN TestExternalSignerErrors/null2459=== RUN TestParseSigningKey/empty_name2460=== RUN TestGenerateFingerprint/unsorted_references_get_sorted2461=== PAUSE TestGenerateFingerprint/unsorted_references_get_sorted2462=== PAUSE TestExternalSignerErrors/null2463--- PASS: TestExternalSignerWrongPublicKey (0.00s)2464=== PAUSE TestParseSigningKey/empty_name2465=== RUN TestGenerateFingerprint/invalid_nar_hash_prefix2466=== PAUSE TestGenerateFingerprint/invalid_nar_hash_prefix2467=== RUN TestExternalSignerErrors/missing_signatures2468=== RUN TestParseSigningKey/invalid_base642469=== PAUSE TestParseSigningKey/invalid_base642470=== RUN TestParseSigningKey/wrong_length2471=== PAUSE TestParseSigningKey/wrong_length2472=== RUN TestGenerateFingerprint/invalid_nar_hash_length2473=== PAUSE TestGenerateFingerprint/invalid_nar_hash_length2474=== PAUSE TestExternalSignerErrors/missing_signatures2475=== RUN TestExternalSignerErrors/exit_at_startup2476=== PAUSE TestExternalSignerErrors/exit_at_startup2477=== RUN TestExternalSignerErrors/exit_on_request2478=== PAUSE TestExternalSignerErrors/exit_on_request2479=== RUN TestExternalSignerErrors/null_signatures2480=== PAUSE TestExternalSignerErrors/null_signatures2481=== CONT TestExternalSignerErrors/too_few_signatures2482=== CONT TestExternalSignerErrors/invalid_JSON2483=== CONT TestExternalSignerErrors/wrong_signature_name2484=== CONT TestParseSigningKey/valid_32-byte_key2485=== CONT TestExternalSignerErrors/missing_signature_name2486=== CONT TestExternalSignerErrors/missing_signatures2487=== CONT TestExternalSignerErrors/wrong_signatures_shape2488=== CONT TestParseSigningKey/invalid_base642489=== CONT TestParseSigningKey/empty_name2490=== CONT TestParseSigningKey/no_colon2491=== CONT TestParseSigningKey/wrong_length2492=== CONT TestParseSigningKey/valid_32-byte_key_with_different_name2493=== RUN TestGenerateFingerprint/invalid_store_path_prefix2494=== PAUSE TestGenerateFingerprint/invalid_store_path_prefix2495=== RUN TestGenerateFingerprint/invalid_reference_prefix2496=== PAUSE TestGenerateFingerprint/invalid_reference_prefix2497=== CONT TestExternalSignerErrors/null_signatures2498=== CONT TestExternalSignerErrors/exit_on_request2499=== CONT TestExternalSignerErrors/wrong_id2500=== CONT TestExternalSignerErrors/exit_at_startup2501=== CONT TestExternalSignerErrors/null2502=== CONT TestExternalSignerErrors/wrong_signature_length2503=== CONT TestExternalSignerErrors/invalid_signature_base642504=== CONT TestExternalSignerErrors/wrong_shape2505=== CONT TestExternalSignerErrors/program_error2506=== CONT TestExternalSignerErrors/unrequested_key2507--- PASS: TestExternalSigner (0.01s)2508=== CONT TestGenerateFingerprint/basic_with_references2509=== CONT TestGenerateFingerprint/no_references2510=== CONT TestGenerateFingerprint/invalid_reference_prefix2511=== CONT TestGenerateFingerprint/invalid_store_path_prefix2512=== CONT TestGenerateFingerprint/invalid_nar_hash_length2513=== CONT TestGenerateFingerprint/invalid_nar_hash_prefix2514=== CONT TestGenerateFingerprint/unsorted_references_get_sorted2515--- PASS: TestParseSigningKey (0.01s)2516 --- PASS: TestParseSigningKey/valid_32-byte_key (0.00s)2517 --- PASS: TestParseSigningKey/invalid_base64 (0.00s)2518 --- PASS: TestParseSigningKey/empty_name (0.00s)2519 --- PASS: TestParseSigningKey/no_colon (0.00s)2520 --- PASS: TestParseSigningKey/wrong_length (0.00s)2521 --- PASS: TestParseSigningKey/valid_32-byte_key_with_different_name (0.00s)2522--- PASS: TestGenerateFingerprint (0.01s)2523 --- PASS: TestGenerateFingerprint/basic_with_references (0.00s)2524 --- PASS: TestGenerateFingerprint/no_references (0.00s)2525 --- PASS: TestGenerateFingerprint/invalid_reference_prefix (0.00s)2526 --- PASS: TestGenerateFingerprint/invalid_nar_hash_prefix (0.00s)2527 --- PASS: TestGenerateFingerprint/unsorted_references_get_sorted (0.00s)2528 --- PASS: TestGenerateFingerprint/invalid_store_path_prefix (0.00s)2529 --- PASS: TestGenerateFingerprint/invalid_nar_hash_length (0.00s)2530--- PASS: TestExternalSignerRestartsAfterCrash (0.01s)2531--- PASS: TestExternalSignerErrors (0.00s)2532 --- PASS: TestExternalSignerErrors/too_few_signatures (0.00s)2533 --- PASS: TestExternalSignerErrors/invalid_JSON (0.00s)2534 --- PASS: TestExternalSignerErrors/missing_signatures (0.01s)2535 --- PASS: TestExternalSignerErrors/missing_signature_name (0.01s)2536 --- PASS: TestExternalSignerErrors/wrong_signature_length (0.01s)2537 --- PASS: TestExternalSignerErrors/wrong_signatures_shape (0.01s)2538 --- PASS: TestExternalSignerErrors/exit_at_startup (0.01s)2539 --- PASS: TestExternalSignerErrors/wrong_shape (0.01s)2540 --- PASS: TestExternalSignerErrors/null (0.01s)2541 --- PASS: TestExternalSignerErrors/wrong_id (0.01s)2542 --- PASS: TestExternalSignerErrors/wrong_signature_name (0.01s)2543 --- PASS: TestExternalSignerErrors/null_signatures (0.01s)2544 --- PASS: TestExternalSignerErrors/exit_on_request (0.01s)2545 --- PASS: TestExternalSignerErrors/invalid_signature_base64 (0.01s)2546 --- PASS: TestExternalSignerErrors/unrequested_key (0.01s)2547 --- PASS: TestExternalSignerErrors/program_error (0.01s)2548--- PASS: TestExternalSignerCancellation (0.02s)2549PASS2550Running OpenBao signer tests...2551=== RUN TestSign2552=== PAUSE TestSign2553=== RUN TestSignErrors2554=== PAUSE TestSignErrors2555=== RUN TestServe2556=== PAUSE TestServe2557=== RUN TestServeRejectsGarbage2558=== PAUSE TestServeRejectsGarbage2559=== CONT TestSign2560=== CONT TestServeRejectsGarbage2561=== CONT TestSignErrors2562--- PASS: TestServeRejectsGarbage (0.00s)2563=== CONT TestServe2564=== RUN TestSignErrors/http_error2565=== PAUSE TestSignErrors/http_error2566=== RUN TestSignErrors/invalid_json2567=== PAUSE TestSignErrors/invalid_json2568=== RUN TestSignErrors/no_prefix2569=== PAUSE TestSignErrors/no_prefix2570=== RUN TestSignErrors/invalid_base642571=== PAUSE TestSignErrors/invalid_base642572=== CONT TestSignErrors/http_error2573=== CONT TestSignErrors/no_prefix2574--- PASS: TestServe (0.00s)2575=== CONT TestSignErrors/invalid_base642576=== CONT TestSignErrors/invalid_json2577--- PASS: TestSign (0.00s)2578--- PASS: TestSignErrors (0.00s)2579 --- PASS: TestSignErrors/invalid_base64 (0.00s)2580 --- PASS: TestSignErrors/http_error (0.00s)2581 --- PASS: TestSignErrors/no_prefix (0.00s)2582 --- PASS: TestSignErrors/invalid_json (0.00s)2583PASS