niks3-go-unit-tests
checks.x86_64-linux.go-unit-tests
· build #363
· 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 TestStreamPushBatchesUnderLoad101=== CONT TestScriptTokenScriptFails102=== CONT TestFileTokenReadsAndCaches103=== CONT TestStreamPushReportsEveryPath104=== CONT TestScriptTokenCachesUntilRefresh105=== CONT TestScriptTokenNoExpiryRerunsEveryCall106--- PASS: TestFileTokenReadsAndCaches (0.00s)107=== CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess108=== CONT TestFileTokenMissing109=== CONT TestFileTokenEmpty110=== CONT TestClientSignaturesByStorePath1112026/10/06 08:15:46 WARN Rate limiter enabled after throttle name=server-test rate=5112=== CONT TestStaticToken113=== CONT TestPathInfoCACompatibility114=== CONT TestSetClientTLSDoesNotMutateDefaultTransport115=== CONT TestSetClientTLS116=== CONT TestStreamPushGivesUpOnDeadServer117=== CONT TestStreamPushReportsSignatures118=== CONT TestScriptTokenEmptyToken119=== CONT TestStreamPushIsolatesFailures120=== CONT TestScriptTokenEmptyCommand121=== CONT TestConvertHashToNix32122=== CONT TestShellSplitErrors123=== CONT TestShellSplit124=== CONT TestDoWithRetry_BodyReplayedViaGetBody125=== CONT TestResolveStorePath126=== CONT TestSetClientTLSErrors127--- PASS: TestClientSignaturesByStorePath (0.00s)128--- PASS: TestStaticToken (0.00s)129--- PASS: TestScriptTokenScriptFails (0.00s)130=== CONT TestRateLimiterFeedback131=== RUN TestRateLimiterFeedback/429_enables_limiter132=== RUN TestPathInfoCACompatibility/null_ca_field133--- PASS: TestFileTokenMissing (0.00s)134=== CONT TestParsePathInfoJSONMultiplePaths135=== RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths136=== PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths137=== RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths138=== RUN TestConvertHashToNix32/SRI_format_to_Nix32139=== PAUSE TestConvertHashToNix32/SRI_format_to_Nix32140=== RUN TestConvertHashToNix32/already_Nix32_format141=== PAUSE TestConvertHashToNix32/already_Nix32_format142=== RUN TestConvertHashToNix32/invalid_format143=== PAUSE TestConvertHashToNix32/invalid_format144=== CONT TestParsePathInfoJSON145=== RUN TestParsePathInfoJSON/Nix_format146=== PAUSE TestParsePathInfoJSON/Nix_format147=== RUN TestParsePathInfoJSON/Lix_format148=== PAUSE TestParsePathInfoJSON/Lix_format149=== CONT TestPartSizeForNAR150=== RUN TestPartSizeForNAR/zero_stays_at_minimum1512026/10/06 08:15:46 ERROR Upload failed error="connection refused" count=20152=== PAUSE TestPartSizeForNAR/zero_stays_at_minimum1532026/10/06 08:15:46 ERROR Server seems unavailable, giving up on batch untried=17154=== RUN TestPartSizeForNAR/small_stays_at_minimum155=== PAUSE TestPartSizeForNAR/small_stays_at_minimum156=== RUN TestPartSizeForNAR/80_GiB_fits_at_minimum157=== PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum158=== RUN TestPartSizeForNAR/115_GiB_needs_larger_parts159=== PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts160=== RUN TestPartSizeForNAR/1_TiB161=== CONT TestEncodeNixBase32WithRealHash162=== CONT TestEncodeNixBase32163=== PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths164--- PASS: TestShellSplitErrors (0.00s)165--- PASS: TestShellSplit (0.00s)166--- PASS: TestFileTokenEmpty (0.00s)167--- PASS: TestEncodeNixBase32WithRealHash (0.00s)168=== CONT TestPathInfoHashCompatibility169=== CONT TestDumpPathSingleFile170=== RUN TestParsePathInfoJSON/empty_input171=== CONT TestDumpPathWriterError172=== RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)173=== PAUSE TestPathInfoCACompatibility/null_ca_field174=== PAUSE TestPartSizeForNAR/1_TiB175--- PASS: TestScriptTokenEmptyCommand (0.00s)176=== PAUSE TestRateLimiterFeedback/429_enables_limiter177=== CONT TestGetStorePathHash178=== PAUSE TestParsePathInfoJSON/empty_input179=== CONT TestDumpPathMatchesNix180--- PASS: TestStreamPushGivesUpOnDeadServer (0.00s)181--- PASS: TestStreamPushReportsEveryPath (0.01s)182=== CONT TestUploadMultipart_SupersededByPeer183=== RUN TestEncodeNixBase32/test_string_hash184=== PAUSE TestEncodeNixBase32/test_string_hash185=== RUN TestEncodeNixBase32/empty_input186=== RUN TestPartSizeForNAR/5_TiB_S3_max_object1872026/10/06 08:15:46 ERROR Upload failed error="bad path" count=3188=== PAUSE TestEncodeNixBase32/empty_input189=== RUN TestPathInfoCACompatibility/old_string_format_-_text190=== RUN TestRateLimiterFeedback/503_enables_limiter191=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)192=== PAUSE TestPartSizeForNAR/5_TiB_S3_max_object193=== RUN TestUploadMultipart_SupersededByPeer/exists194--- PASS: TestResolveStorePath (0.00s)195=== CONT TestCaseHackSuffix196=== CONT TestScriptTokenBadJSON197=== PAUSE TestRateLimiterFeedback/503_enables_limiter198=== PAUSE TestPathInfoCACompatibility/old_string_format_-_text1992026/10/06 08:15:46 WARN Rate limiter enabled after throttle name=server-test rate=52002026/10/06 08:15:46 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:42215201=== PAUSE TestUploadMultipart_SupersededByPeer/exists202=== RUN TestParsePathInfoJSON/whitespace_only2032026/10/06 08:15:46 ERROR Upload failed error=boom count=1204=== RUN TestGetStorePathHash/valid_store_path205=== RUN TestUploadMultipart_SupersededByPeer/missing206=== RUN TestPartSizeForNAR/capped_at_5_GiB207=== PAUSE TestPartSizeForNAR/capped_at_5_GiB208=== RUN TestPathInfoHashCompatibility/old_string_format_with_colon209--- PASS: TestStreamPushIsolatesFailures (0.00s)210--- PASS: TestStreamPushReportsSignatures (0.00s)211=== CONT TestUploadMultipart_PartsInParallel212=== RUN TestRateLimiterFeedback/200_does_not_enable_limiter213--- PASS: TestScriptTokenBadJSON (0.00s)214=== RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive215=== PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive216=== RUN TestPathInfoCACompatibility/new_structured_format_-_text217=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_text218=== PAUSE TestGetStorePathHash/valid_store_path219--- PASS: TestScriptTokenEmptyToken (0.01s)220=== CONT TestRegisterUploadedObjectReusesConnections221=== RUN TestSetClientTLSErrors/missing_cert_file222=== CONT TestFilterOversizedClosures223=== RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method224=== RUN TestFilterOversizedClosures/no_limit_keeps_everything225=== RUN TestGetStorePathHash/basename_without_hyphen_should_error226=== PAUSE TestUploadMultipart_SupersededByPeer/missing227=== PAUSE TestSetClientTLSErrors/missing_cert_file228=== RUN TestSetClientTLSErrors/missing_key_file229=== PAUSE TestSetClientTLSErrors/missing_key_file230=== RUN TestSetClientTLSErrors/missing_ca_file231=== PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter232=== PAUSE TestParsePathInfoJSON/whitespace_only233=== CONT TestRunGarbageCollection_FinishedOnAnotherReplica234=== CONT TestRunGarbageCollection_NotFoundAfterLocalRun235--- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.01s)236=== CONT TestConvertHashToNix32/SRI_format_to_Nix32237=== PAUSE TestFilterOversizedClosures/no_limit_keeps_everything238=== CONT TestConvertHashToNix32/invalid_format239=== RUN TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped240=== PAUSE TestSetClientTLSErrors/missing_ca_file241=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon2422026/10/06 08:15:46 WARN Rate limiter backed off name=server-test rate=52432026/10/06 08:15:46 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:422152442026/10/06 08:15:46 INFO Garbage collection started245=== RUN TestRateLimiterFeedback/400_does_not_enable_limiter246=== CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths247=== RUN TestParsePathInfoJSON/invalid_JSON248=== CONT TestStreamPushRequestLine249=== PAUSE TestGetStorePathHash/basename_without_hyphen_should_error250=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method251=== RUN TestSetClientTLS/rejects_connection_without_client_cert252=== RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI253--- PASS: TestDoServerRequestAttachesToken (0.03s)254=== PAUSE TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped255=== RUN TestSetClientTLSErrors/invalid_ca_file256=== CONT TestConvertHashToNix32/already_Nix32_format257=== CONT TestPartSizeForNAR/zero_stays_at_minimum258--- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.06s)2592026/10/06 08:15:46 ERROR Upload failed error=boom count=1260=== CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths261=== CONT TestEncodeNixBase32/empty_input262=== PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter263=== CONT TestPartSizeForNAR/small_stays_at_minimum264=== CONT TestUploadMultipart_SupersededByPeer/missing265=== PAUSE TestParsePathInfoJSON/invalid_JSON266=== RUN TestGetStorePathHash/hash_with_invalid_characters_should_error267=== PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error268=== CONT TestPartSizeForNAR/1_TiB269=== CONT TestPartSizeForNAR/115_GiB_needs_larger_parts270=== PAUSE TestSetClientTLS/rejects_connection_without_client_cert271=== CONT TestPartSizeForNAR/5_TiB_S3_max_object272=== PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI273=== PAUSE TestSetClientTLSErrors/invalid_ca_file274=== CONT TestEncodeNixBase32/test_string_hash275=== CONT TestPartSizeForNAR/80_GiB_fits_at_minimum2762026/10/06 08:15:46 INFO Garbage collection started277=== CONT TestRateLimiterFeedback/400_does_not_enable_limiter278=== RUN TestFilterOversizedClosures/all_closures_skipped279--- PASS: TestScriptTokenCachesUntilRefresh (0.06s)280=== CONT TestPartSizeForNAR/capped_at_5_GiB281=== CONT TestUploadMultipart_SupersededByPeer/exists282=== CONT TestPathInfoCACompatibility/null_ca_field283=== CONT TestRateLimiterFeedback/503_enables_limiter284=== RUN TestGetStorePathHash/hash_with_wrong_length_should_error285=== CONT TestParsePathInfoJSON/Nix_format286=== PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error287=== CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive288=== CONT TestParsePathInfoJSON/whitespace_only289=== CONT TestParsePathInfoJSON/Lix_format290=== CONT TestSetClientTLSErrors/missing_ca_file291=== CONT TestParsePathInfoJSON/invalid_JSON292=== CONT TestSetClientTLSErrors/invalid_ca_file293=== CONT TestSetClientTLSErrors/missing_key_file294=== CONT TestSetClientTLSErrors/missing_cert_file295=== CONT TestGetStorePathHash/hash_with_wrong_length_should_error296=== CONT TestGetStorePathHash/hash_with_invalid_characters_should_error297=== CONT TestGetStorePathHash/valid_store_path298=== CONT TestGetStorePathHash/basename_without_hyphen_should_error299=== RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512300=== PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha5123012026/10/06 08:15:46 WARN Rate limiter enabled after throttle name=server-test rate=5302=== CONT TestPathInfoCACompatibility/old_string_format_-_text3032026/10/06 08:15:46 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:43219304=== CONT TestRateLimiterFeedback/429_enables_limiter305=== CONT TestPathInfoCACompatibility/new_structured_format_-_text306=== PAUSE TestFilterOversizedClosures/all_closures_skipped307=== CONT TestFilterOversizedClosures/no_limit_keeps_everything3082026/10/06 08:15:46 WARN Rate limiter backed off name=server-test rate=5309=== CONT TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped3102026/10/06 08:15:46 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=2000311--- PASS: TestRunGarbageCollection_NotFoundAfterLocalRun (0.05s)312--- PASS: TestConvertHashToNix32 (0.00s)313 --- PASS: TestConvertHashToNix32/invalid_format (0.00s)314 --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.03s)315 --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)316=== CONT TestFilterOversizedClosures/all_closures_skipped317--- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)318 --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)319 --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)3202026/10/06 08:15:46 WARN Rate limiter enabled after throttle name=server-test rate=5321=== CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method322=== CONT TestParsePathInfoJSON/empty_input3232026/10/06 08:15:46 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:38377324=== RUN TestSetClientTLS/succeeds_with_client_cert_and_CA3252026/10/06 08:15:46 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=50326=== PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA3272026/10/06 08:15:46 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=0328=== RUN TestSetClientTLS/preserves_debug_logging_transport3292026/10/06 08:15:46 WARN Rate limiter backed off name=server-test rate=5330=== PAUSE TestSetClientTLS/preserves_debug_logging_transport331=== CONT TestSetClientTLS/rejects_connection_without_client_cert332=== CONT TestSetClientTLS/preserves_debug_logging_transport333=== CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)334=== CONT TestSetClientTLS/succeeds_with_client_cert_and_CA335=== CONT TestPathInfoHashCompatibility/old_string_format_with_colon336=== CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512337=== CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI3382026/10/06 08:15:46 WARN Garbage collection finished on another replica. Its statistics are not available here339=== CONT TestRateLimiterFeedback/200_does_not_enable_limiter340--- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)341--- PASS: TestDumpPathSingleFile (0.06s)342--- PASS: TestRunGarbageCollection_FinishedOnAnotherReplica (0.06s)343--- PASS: TestGetStorePathHash (0.06s)344 --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)345 --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)346 --- PASS: TestGetStorePathHash/valid_store_path (0.00s)347 --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)348--- PASS: TestEncodeNixBase32 (0.00s)349 --- PASS: TestEncodeNixBase32/empty_input (0.00s)350 --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)351--- PASS: TestPartSizeForNAR (0.01s)352 --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)353 --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)354 --- PASS: TestPartSizeForNAR/1_TiB (0.00s)355 --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)356 --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)357 --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)358 --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)359--- PASS: TestUploadMultipart_SupersededByPeer (0.00s)360 --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)361 --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)362--- PASS: TestParsePathInfoJSON (0.06s)363 --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)364 --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)365 --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)366 --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)367 --- PASS: TestParsePathInfoJSON/empty_input (0.00s)368--- PASS: TestPathInfoHashCompatibility (0.06s)369 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)370 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)371 --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)372 --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)373--- PASS: TestPathInfoCACompatibility (0.06s)374 --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)375 --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)376 --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)377 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)378 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)379--- PASS: TestFilterOversizedClosures (0.05s)380 --- PASS: TestFilterOversizedClosures/no_limit_keeps_everything (0.00s)381 --- PASS: TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped (0.00s)382 --- PASS: TestFilterOversizedClosures/all_closures_skipped (0.00s)383--- PASS: TestRateLimiterFeedback (0.06s)384 --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)385 --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)386 --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)387 --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)388--- PASS: TestSetClientTLSErrors (0.06s)389 --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)390 --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)391 --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)392 --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)393--- PASS: TestCaseHackSuffix (0.07s)3942026/10/06 08:15:47 http: TLS handshake error from 127.0.0.1:45350: remote error: tls: bad certificate395--- PASS: TestSetClientTLS (0.06s)396 --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)397 --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)398 --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)399--- PASS: TestStreamPushRequestLine (0.03s)400--- PASS: TestRegisterUploadedObjectReusesConnections (0.09s)401--- PASS: TestDumpPathWriterError (0.11s)402--- PASS: TestStreamPushBatchesUnderLoad (0.12s)403--- PASS: TestDumpPathMatchesNix (0.15s)404--- PASS: TestUploadMultipart_PartsInParallel (0.67s)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/postgres1912412946/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/postgres1912412946/data -l logfile start434435/build/postgres1912412946:5432 - no response4362026-10-06 08:15:48.836 UTC [129] LOG: starting PostgreSQL 18.6 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit4372026-10-06 08:15:48.837 UTC [129] LOG: listening on Unix socket "/build/postgres1912412946/.s.PGSQL.5432"4382026-10-06 08:15:48.842 UTC [136] LOG: database system was shut down at 2026-10-06 08:15:48 UTC4392026-10-06 08:15:48.851 UTC [129] LOG: database system is ready to accept connections440/build/postgres1912412946:5432 - accepting connections441=== RUN TestService_AuthMiddleware442=== PAUSE TestService_AuthMiddleware443=== RUN TestService_AuthMiddleware_MTLSProxyHeader444=== PAUSE TestService_AuthMiddleware_MTLSProxyHeader445=== RUN TestService_AuthMiddleware_MTLSBoundSubjects446=== PAUSE TestService_AuthMiddleware_MTLSBoundSubjects447=== RUN TestService_ReadAuthMiddleware448=== PAUSE TestService_ReadAuthMiddleware449=== RUN TestService_AuthMiddleware_OIDC450=== PAUSE TestService_AuthMiddleware_OIDC451=== RUN TestService_RequireScope_OIDC452=== PAUSE TestService_RequireScope_OIDC453=== RUN TestService_ReadScope_PublicByDefault454=== PAUSE TestService_ReadScope_PublicByDefault455=== RUN TestCacheConfigHandler456=== PAUSE TestCacheConfigHandler457=== RUN TestCacheStatsHandler458=== PAUSE TestCacheStatsHandler459=== RUN TestClientCADerivations460=== PAUSE TestClientCADerivations461=== RUN TestClientErrorHandling462=== PAUSE TestClientErrorHandling463=== RUN TestClientIntegration464=== PAUSE TestClientIntegration465=== RUN TestClientMultipleUploads466=== PAUSE TestClientMultipleUploads467=== RUN TestClientWithDependencies468=== PAUSE TestClientWithDependencies469=== RUN TestClientSharedPathCommittedMidPush470=== PAUSE TestClientSharedPathCommittedMidPush471=== RUN TestPinProtectsFromGC472=== PAUSE TestPinProtectsFromGC473=== RUN TestClientPushesUseOnePush474=== PAUSE TestClientPushesUseOnePush475=== RUN TestClientFallsBackToClosures476=== PAUSE TestClientFallsBackToClosures477=== RUN TestConcurrentCommitsSharingObjectsDoNotDeadlock478=== PAUSE TestConcurrentCommitsSharingObjectsDoNotDeadlock479=== RUN TestResolveDBConnectionString480=== PAUSE TestResolveDBConnectionString481=== RUN TestConnectWaitsForAPeerMigration482=== PAUSE TestConnectWaitsForAPeerMigration483=== RUN TestLeadElectsOneAndHandsOver484=== PAUSE TestLeadElectsOneAndHandsOver485=== RUN TestLeadIncumbentWinsAfterRestart4862026/10/06 08:15:49 INFO lead: acquired remote=192.0.2.1:12344872026/10/06 08:15:50 INFO lead: released remote=192.0.2.1:12344882026/10/06 08:15:50 INFO lead: acquired remote=192.0.2.1:12344892026/10/06 08:15:51 INFO lead: released remote=192.0.2.1:1234490--- PASS: TestLeadIncumbentWinsAfterRestart (1.86s)491=== RUN TestLeadIncumbentAnnouncesAtOnce492=== PAUSE TestLeadIncumbentAnnouncesAtOnce493=== RUN TestLeadEndsOnShutdown494=== PAUSE TestLeadEndsOnShutdown495=== RUN TestLeadEndsWhenItsConnectionHangs4962026/10/06 08:15:51 INFO lead: acquired remote=192.0.2.1:12344972026-10-06 08:15:52.639 UTC [578] FATAL: terminating connection due to administrator command4982026/10/06 08:15:52 INFO lead: acquired remote=192.0.2.1:12344992026/10/06 08:15:53 WARN lead: lock connection lost error="timeout: context deadline exceeded"5002026/10/06 08:15:53 INFO lead: released remote=192.0.2.1:12345012026/10/06 08:15:54 INFO lead: released remote=192.0.2.1:1234502--- PASS: TestLeadEndsWhenItsConnectionHangs (2.92s)503=== RUN TestGCAdvisoryLockBlocksConcurrentRun504=== PAUSE TestGCAdvisoryLockBlocksConcurrentRun505=== RUN TestGCBugBareHashReferences506=== PAUSE TestGCBugBareHashReferences507=== RUN TestGCMetrics508=== PAUSE TestGCMetrics509=== RUN TestPushDedupSurvivesConcurrentGC510=== PAUSE TestPushDedupSurvivesConcurrentGC511=== RUN TestDeduplicatedObjectsRecordedAsPending512=== PAUSE TestDeduplicatedObjectsRecordedAsPending513=== RUN TestGCSweepSkipsPendingObjects514=== PAUSE TestGCSweepSkipsPendingObjects515=== RUN TestTombstonedObjectOfferedWithoutWaiting516=== PAUSE TestTombstonedObjectOfferedWithoutWaiting517=== RUN TestGCSweepDeliversEachKeyOnce518=== PAUSE TestGCSweepDeliversEachKeyOnce519=== RUN TestCreatePendingClosureVerifyS3FailureReleasesConnection520=== PAUSE TestCreatePendingClosureVerifyS3FailureReleasesConnection521=== RUN TestForceGCDuringPushOffersSweptObject522=== PAUSE TestForceGCDuringPushOffersSweptObject523=== RUN TestFailedS3DeleteKeepsTombstone524=== PAUSE TestFailedS3DeleteKeepsTombstone525=== RUN TestSweepSkipsRowsLockedByPush526=== PAUSE TestSweepSkipsRowsLockedByPush527=== RUN TestRegisterCompletedObjectNeedsPendingRow528=== PAUSE TestRegisterCompletedObjectNeedsPendingRow529=== RUN TestGetExistingObjectsLocksRows530=== PAUSE TestGetExistingObjectsLocksRows531=== RUN TestSweepRechecksPendingAfterLocking532=== PAUSE TestSweepRechecksPendingAfterLocking533=== RUN TestPushWaitsForSweepHoldingRow534=== PAUSE TestPushWaitsForSweepHoldingRow535=== RUN TestCommitRacingPendingCleanupKeepsObjects536=== PAUSE TestCommitRacingPendingCleanupKeepsObjects537=== RUN TestGCEndsOnShutdown538=== PAUSE TestGCEndsOnShutdown539=== RUN TestGCTaskStore_StartNew540=== PAUSE TestGCTaskStore_StartNew541=== RUN TestGCTaskStore_DeduplicateSameParams542=== PAUSE TestGCTaskStore_DeduplicateSameParams543=== RUN TestGCTaskStore_ConflictDifferentParams544=== PAUSE TestGCTaskStore_ConflictDifferentParams545=== RUN TestGCTaskStore_GetEmpty546=== PAUSE TestGCTaskStore_GetEmpty547=== RUN TestGCTaskStore_GetReturnsLatest548=== PAUSE TestGCTaskStore_GetReturnsLatest549=== RUN TestGCTaskStore_CompletedAllowsNewTask550=== PAUSE TestGCTaskStore_CompletedAllowsNewTask551=== RUN TestGCTaskStore_PhaseUpdates552=== PAUSE TestGCTaskStore_PhaseUpdates553=== RUN TestGCTaskStore_Fail554=== PAUSE TestGCTaskStore_Fail555=== RUN TestGracefulShutdownDrainsInflight556=== PAUSE TestGracefulShutdownDrainsInflight557=== RUN TestService_healthCheckHandler558=== PAUSE TestService_healthCheckHandler559=== RUN TestService_readinessHandler560=== PAUSE TestService_readinessHandler561=== RUN TestGenerateLandingPage562=== PAUSE TestGenerateLandingPage563=== RUN TestCacheConfigHandlerMaxNarSize564=== PAUSE TestCacheConfigHandlerMaxNarSize565=== RUN TestCreatePendingClosureRejectsOversizedNAR566=== PAUSE TestCreatePendingClosureRejectsOversizedNAR567=== RUN TestNARDeduplicationMetadataUploadBug568=== PAUSE TestNARDeduplicationMetadataUploadBug569=== RUN TestMetricsInventory570=== PAUSE TestMetricsInventory571=== RUN TestService_NativeMTLS572=== PAUSE TestService_NativeMTLS573=== RUN TestServerTLSConfig574=== PAUSE TestServerTLSConfig575=== RUN TestMultipartCleanup576=== PAUSE TestMultipartCleanup577=== RUN TestMultipartUploadAbortedWhenCancelledBeforeRecorded578=== PAUSE TestMultipartUploadAbortedWhenCancelledBeforeRecorded579=== RUN TestPendingCleanupUsesOneCutoff580=== PAUSE TestPendingCleanupUsesOneCutoff581=== RUN TestObjectStatsTrigger582=== PAUSE TestObjectStatsTrigger583=== RUN TestOrphanedObjectsGC584=== PAUSE TestOrphanedObjectsGC585=== RUN TestOrphanedObjectsGCStressTest586=== PAUSE TestOrphanedObjectsGCStressTest587=== RUN TestCreatePin_ReservedPins588=== PAUSE TestCreatePin_ReservedPins589=== RUN TestPresentReportsOnlyClosureRoots590=== PAUSE TestPresentReportsOnlyClosureRoots591=== RUN TestPresentNotReportedWhileGCDeletesClosure592=== PAUSE TestPresentNotReportedWhileGCDeletesClosure593=== RUN TestParseSingleRange594=== PAUSE TestParseSingleRange595=== RUN TestProxyHeadersOnlyTrustedOnSocket596=== PAUSE TestProxyHeadersOnlyTrustedOnSocket597=== RUN TestIsValidCachePath598=== PAUSE TestIsValidCachePath599=== RUN TestReadProxyNarinfo600=== PAUSE TestReadProxyNarinfo601=== RUN TestReadProxyNarinfoAlreadyDecompressed602=== PAUSE TestReadProxyNarinfoAlreadyDecompressed603=== RUN TestReadProxyNarStreaming604=== PAUSE TestReadProxyNarStreaming605=== RUN TestReadProxy404606=== PAUSE TestReadProxy404607=== RUN TestReadProxyInvalidPath608=== PAUSE TestReadProxyInvalidPath609=== RUN TestReadProxyHead610=== PAUSE TestReadProxyHead611=== RUN TestReadProxyOutlastsServerWriteTimeout612=== PAUSE TestReadProxyOutlastsServerWriteTimeout613=== RUN TestReadProxyConditionalGet614=== PAUSE TestReadProxyConditionalGet615=== RUN TestReadProxyRootRedirectsToIndexHTML616=== PAUSE TestReadProxyRootRedirectsToIndexHTML617=== RUN TestReadProxyDisabled618=== PAUSE TestReadProxyDisabled619=== RUN TestReadRedirectNar620=== PAUSE TestReadRedirectNar621=== RUN TestReadRedirectKeepsNarinfoProxied622=== PAUSE TestReadRedirectKeepsNarinfoProxied623=== RUN TestReadProxyRangeRequest624=== PAUSE TestReadProxyRangeRequest625=== RUN TestReadRedirectUsesPublicS3URL626=== PAUSE TestReadRedirectUsesPublicS3URL627=== RUN TestPush_OverlappingRootsStoreOneRowPerKey628=== PAUSE TestPush_OverlappingRootsStoreOneRowPerKey629=== RUN TestPush_CompleteCommitsEveryRoot630=== PAUSE TestPush_CompleteCommitsEveryRoot631=== RUN TestPush_SkippedKeySurvivesGCBeforeCommit632=== PAUSE TestPush_SkippedKeySurvivesGCBeforeCommit633=== RUN TestPush_RejectsBadRequests634=== PAUSE TestPush_RejectsBadRequests635=== RUN TestPush_SignsNarinfosOfItsPendingObjects636=== PAUSE TestPush_SignsNarinfosOfItsPendingObjects637=== RUN TestRedundantMultipartUpload638=== PAUSE TestRedundantMultipartUpload639=== RUN TestCompleteMultipartUpload_ErrorButObjectExists640=== PAUSE TestCompleteMultipartUpload_ErrorButObjectExists641=== RUN TestCompletedNarNotReofferedAcrossClosures642=== PAUSE TestCompletedNarNotReofferedAcrossClosures643=== RUN TestPresignedUploadRegisteredBeforeCommit644=== PAUSE TestPresignedUploadRegisteredBeforeCommit645=== RUN TestService_Rustfstest646=== PAUSE TestService_Rustfstest647=== RUN TestParseSize648=== PAUSE TestParseSize649=== RUN TestSkippedUploadsHandler650=== PAUSE TestSkippedUploadsHandler651=== RUN TestSystemdListenerNotActivated652--- PASS: TestSystemdListenerNotActivated (0.00s)653=== RUN TestWatchdogBeatsWhenHealthy654--- PASS: TestWatchdogBeatsWhenHealthy (0.02s)655=== RUN TestWatchdogSkipsWhenUnhealthy6562026/10/06 08:15:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6572026/10/06 08:15:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6582026/10/06 08:15:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6592026/10/06 08:15:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6602026/10/06 08:15:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6612026/10/06 08:15:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6622026/10/06 08:15:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6632026/10/06 08:15:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6642026/10/06 08:15:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6652026/10/06 08:15:54 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"666--- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)667=== RUN TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle668=== PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle669=== RUN TestProxyWriteTimeout670=== PAUSE TestProxyWriteTimeout671=== RUN TestPendingClosureWriteTimeout672=== PAUSE TestPendingClosureWriteTimeout673=== RUN TestIsValidUploadKey674=== PAUSE TestIsValidUploadKey675=== RUN TestUploadHandlersRejectInvalidKeys676=== PAUSE TestUploadHandlersRejectInvalidKeys677=== RUN TestUploadHandlersRejectOversizedBody678=== PAUSE TestUploadHandlersRejectOversizedBody679=== RUN TestService_cleanupPendingClosuresHandler680=== PAUSE TestService_cleanupPendingClosuresHandler681=== RUN TestService_createPendingClosureHandler682=== PAUSE TestService_createPendingClosureHandler683=== RUN TestService_verifyS3Integrity684=== PAUSE TestService_verifyS3Integrity685=== RUN TestCompleteMultipartUnregistered686=== PAUSE TestCompleteMultipartUnregistered687=== RUN TestCreatePendingClosure_SmallNARUsesSimplePUT688=== PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT689=== CONT TestReadRedirectNar690=== CONT TestService_AuthMiddleware691=== CONT TestReadProxyDisabled692=== CONT TestReadProxyRootRedirectsToIndexHTML693=== CONT TestReadProxyConditionalGet694=== CONT TestReadProxyOutlastsServerWriteTimeout695=== CONT TestReadProxyHead696=== CONT TestReadProxyInvalidPath697=== CONT TestGetExistingObjectsLocksRows698=== CONT TestRegisterCompletedObjectNeedsPendingRow699=== CONT TestSweepSkipsRowsLockedByPush700=== CONT TestFailedS3DeleteKeepsTombstone701=== CONT TestSweepRechecksPendingAfterLocking702=== CONT TestForceGCDuringPushOffersSweptObject703=== CONT TestReadProxy404704=== CONT TestReadProxyNarStreaming705=== CONT TestCreatePendingClosureVerifyS3FailureReleasesConnection706=== CONT TestReadProxyNarinfoAlreadyDecompressed707=== CONT TestGCSweepDeliversEachKeyOnce708=== CONT TestReadProxyNarinfo709=== CONT TestTombstonedObjectOfferedWithoutWaiting710=== CONT TestIsValidCachePath711=== RUN TestIsValidCachePath/narinfo712=== PAUSE TestIsValidCachePath/narinfo713=== RUN TestIsValidCachePath/narinfo_all_nix_base32_chars714=== CONT TestGCSweepSkipsPendingObjects715=== CONT TestDeduplicatedObjectsRecordedAsPending716=== RUN TestForceGCDuringPushOffersSweptObject/before_pending_rows717=== PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars718=== RUN TestIsValidCachePath/nar_zst719=== PAUSE TestIsValidCachePath/nar_zst720=== RUN TestIsValidCachePath/nar_xz721=== PAUSE TestIsValidCachePath/nar_xz722=== RUN TestIsValidCachePath/nar_bz2723=== PAUSE TestIsValidCachePath/nar_bz2724=== RUN TestIsValidCachePath/nar_uncompressed725=== PAUSE TestForceGCDuringPushOffersSweptObject/before_pending_rows726=== RUN TestForceGCDuringPushOffersSweptObject/after_presence_check727=== PAUSE TestForceGCDuringPushOffersSweptObject/after_presence_check728=== PAUSE TestIsValidCachePath/nar_uncompressed729=== CONT TestProxyHeadersOnlyTrustedOnSocket730=== RUN TestIsValidCachePath/ls731=== PAUSE TestIsValidCachePath/ls732=== RUN TestIsValidCachePath/log733=== PAUSE TestIsValidCachePath/log734=== RUN TestIsValidCachePath/realisation735=== PAUSE TestIsValidCachePath/realisation736=== RUN TestIsValidCachePath/nix-cache-info737=== PAUSE TestIsValidCachePath/nix-cache-info738=== RUN TestIsValidCachePath/index.html739=== PAUSE TestIsValidCachePath/index.html740=== RUN TestIsValidCachePath/traversal_parent741=== PAUSE TestIsValidCachePath/traversal_parent742=== RUN TestIsValidCachePath/traversal_in_middle743=== PAUSE TestIsValidCachePath/traversal_in_middle744=== RUN TestIsValidCachePath/invalid_char_e745=== PAUSE TestIsValidCachePath/invalid_char_e746=== RUN TestIsValidCachePath/invalid_char_u747=== PAUSE TestIsValidCachePath/invalid_char_u748=== RUN TestIsValidCachePath/random_path749=== PAUSE TestIsValidCachePath/random_path750=== RUN TestIsValidCachePath/empty751=== PAUSE TestIsValidCachePath/empty752=== RUN TestIsValidCachePath/leading_slash753=== PAUSE TestIsValidCachePath/leading_slash754=== RUN TestIsValidCachePath/wrong_extension755=== PAUSE TestIsValidCachePath/wrong_extension756=== RUN TestIsValidCachePath/short_hash757=== PAUSE TestIsValidCachePath/short_hash758=== CONT TestPushDedupSurvivesConcurrentGC759--- PASS: TestReadProxyRootRedirectsToIndexHTML (0.68s)760=== CONT TestParseSingleRange761=== RUN TestParseSingleRange/none762=== PAUSE TestParseSingleRange/none763=== RUN TestParseSingleRange/unknown_unit764=== PAUSE TestParseSingleRange/unknown_unit765=== RUN TestParseSingleRange/multi-range_ignored766=== PAUSE TestParseSingleRange/multi-range_ignored767=== RUN TestParseSingleRange/malformed_no_dash768=== PAUSE TestParseSingleRange/malformed_no_dash769=== RUN TestParseSingleRange/malformed_both_empty770=== PAUSE TestParseSingleRange/malformed_both_empty771=== RUN TestParseSingleRange/malformed_end_before_start772=== PAUSE TestParseSingleRange/malformed_end_before_start773=== RUN TestParseSingleRange/closed774=== PAUSE TestParseSingleRange/closed775=== RUN TestParseSingleRange/open-ended776=== PAUSE TestParseSingleRange/open-ended777=== RUN TestParseSingleRange/end_clamped_to_size778=== PAUSE TestParseSingleRange/end_clamped_to_size779=== RUN TestParseSingleRange/suffix780=== PAUSE TestParseSingleRange/suffix781=== RUN TestParseSingleRange/suffix_exceeds_size782=== PAUSE TestParseSingleRange/suffix_exceeds_size783=== RUN TestParseSingleRange/single_byte784=== PAUSE TestParseSingleRange/single_byte785=== RUN TestParseSingleRange/start_past_EOF786=== PAUSE TestParseSingleRange/start_past_EOF787=== RUN TestParseSingleRange/start_far_past_EOF788=== PAUSE TestParseSingleRange/start_far_past_EOF789=== CONT TestGCMetrics790--- PASS: TestReadRedirectNar (0.70s)791=== CONT TestGCBugBareHashReferences7922026/10/06 08:15:54 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"793--- PASS: TestService_AuthMiddleware (0.70s)794=== CONT TestGCAdvisoryLockBlocksConcurrentRun795--- PASS: TestReadProxyHead (0.73s)796=== CONT TestPresentNotReportedWhileGCDeletesClosure797--- PASS: TestReadProxyDisabled (0.74s)798=== CONT TestLeadEndsOnShutdown799--- PASS: TestReadProxyInvalidPath (0.76s)800=== CONT TestPresentReportsOnlyClosureRoots801--- PASS: TestGetExistingObjectsLocksRows (0.80s)802=== CONT TestLeadIncumbentAnnouncesAtOnce803--- PASS: TestReadProxyConditionalGet (0.81s)804=== CONT TestCreatePin_ReservedPins805--- PASS: TestReadProxyNarStreaming (0.82s)806=== CONT TestOrphanedObjectsGCStressTest8072026/10/06 08:15:55 ERROR Failed to decompress narinfo error="decompressed size exceeds configured limit"8082026/10/06 08:15:55 INFO Received uploads request method=POST path=/api/pending_closures8092026/10/06 08:15:55 INFO Aborted multipart uploads count=0 kept=08102026/10/06 08:15:55 WARN Force mode enabled - objects will be deleted immediately without grace period8112026/10/06 08:15:55 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:43485/oidc812--- PASS: TestRegisterCompletedObjectNeedsPendingRow (0.89s)813=== CONT TestLeadElectsOneAndHandsOver8142026/10/06 08:15:55 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=08152026/10/06 08:15:55 INFO Vacuumed table table=pending_closures8162026/10/06 08:15:55 INFO Vacuumed table table=pending_objects8172026/10/06 08:15:55 INFO Received uploads request method=POST path=/api/pending_closures8182026/10/06 08:15:55 INFO Vacuumed table table=multipart_uploads8192026/10/06 08:15:55 INFO Vacuumed table table=closures8202026/10/06 08:15:55 INFO Vacuumed table table=objects821--- PASS: TestGCSweepDeliversEachKeyOnce (0.90s)822=== CONT TestOrphanedObjectsGC823--- PASS: TestTombstonedObjectOfferedWithoutWaiting (0.90s)824=== CONT TestConnectWaitsForAPeerMigration8252026/10/06 08:15:55 INFO Received uploads request method=POST path=/api/pending_closures826--- PASS: TestDeduplicatedObjectsRecordedAsPending (0.92s)827=== CONT TestObjectStatsTrigger828--- PASS: TestReadProxy404 (0.95s)829=== CONT TestResolveDBConnectionString830=== RUN TestResolveDBConnectionString/flag_wins831=== PAUSE TestResolveDBConnectionString/flag_wins832=== RUN TestResolveDBConnectionString/file_when_flag_empty833=== PAUSE TestResolveDBConnectionString/file_when_flag_empty834=== RUN TestResolveDBConnectionString/missing_file_is_an_error835=== PAUSE TestResolveDBConnectionString/missing_file_is_an_error836=== RUN TestResolveDBConnectionString/PGHOST_allows_empty837=== PAUSE TestResolveDBConnectionString/PGHOST_allows_empty838=== RUN TestResolveDBConnectionString/nothing_configured839=== PAUSE TestResolveDBConnectionString/nothing_configured840=== CONT TestPendingCleanupUsesOneCutoff8412026/10/06 08:15:55 INFO Received uploads request method=POST path=/api/pending_closures8422026/10/06 08:15:55 INFO Aborted multipart uploads count=0 kept=08432026/10/06 08:15:55 WARN Force mode enabled - objects will be deleted immediately without grace period8442026/10/06 08:15:55 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=08452026/10/06 08:15:55 INFO Aborted multipart uploads count=0 kept=08462026/10/06 08:15:55 WARN Force mode enabled - objects will be deleted immediately without grace period8472026/10/06 08:15:55 ERROR Refusing narinfo larger than the limit key=5hcdxyjf9yiq7qf3i4548drb6sjmwa1v.narinfo limit=16777216848--- PASS: TestReadProxyNarinfo (1.02s)849=== CONT TestMultipartUploadAbortedWhenCancelledBeforeRecorded8502026/10/06 08:15:55 INFO Vacuumed table table=pending_closures8512026/10/06 08:15:55 INFO Vacuumed table table=pending_objects8522026/10/06 08:15:55 INFO Vacuumed table table=multipart_uploads8532026/10/06 08:15:55 INFO Vacuumed table table=closures8542026/10/06 08:15:55 INFO Vacuumed table table=objects8552026/10/06 08:15:55 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=08562026/10/06 08:15:55 INFO Starting HTTP server address=127.0.0.1:332838572026/10/06 08:15:55 INFO Starting HTTP server address=/build/TestProxyHeadersOnlyTrustedOnSocket2878676737/001/proxy.sock8582026/10/06 08:15:55 WARN mTLS auth: subject not in bound subjects subject="CN=someone"8592026/10/06 08:15:55 INFO Shutdown signal received, draining in-flight requests timeout=10s860--- PASS: TestProxyHeadersOnlyTrustedOnSocket (1.06s)861=== CONT TestMultipartCleanup8622026/10/06 08:15:55 INFO Vacuumed table table=pending_closures863--- PASS: TestGCSweepSkipsPendingObjects (1.06s)864=== CONT TestConcurrentCommitsSharingObjectsDoNotDeadlock8652026/10/06 08:15:55 INFO Vacuumed table table=pending_objects8662026/10/06 08:15:55 INFO Vacuumed table table=multipart_uploads8672026/10/06 08:15:55 INFO Vacuumed table table=closures8682026/10/06 08:15:55 INFO Vacuumed table table=objects869--- PASS: TestSweepRechecksPendingAfterLocking (1.08s)870=== CONT TestServerTLSConfig871=== RUN TestServerTLSConfig/no_client_CA872=== PAUSE TestServerTLSConfig/no_client_CA873=== RUN TestServerTLSConfig/missing_CA_file874=== PAUSE TestServerTLSConfig/missing_CA_file875=== RUN TestServerTLSConfig/not_a_PEM_file876=== PAUSE TestServerTLSConfig/not_a_PEM_file877=== CONT TestClientFallsBackToClosures878--- PASS: TestCreatePendingClosureVerifyS3FailureReleasesConnection (1.08s)879=== CONT TestService_NativeMTLS8802026/10/06 08:15:55 INFO Received uploads request method=POST path=/api/pending_closures881--- PASS: TestReadProxyNarinfoAlreadyDecompressed (1.11s)882=== CONT TestClientPushesUseOnePush8832026/10/06 08:15:55 INFO Aborted multipart uploads count=0 kept=08842026/10/06 08:15:55 INFO Aborted multipart uploads count=0 kept=08852026/10/06 08:15:55 WARN Force mode enabled - objects will be deleted immediately without grace period8862026/10/06 08:15:55 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=08872026/10/06 08:15:55 WARN Force mode enabled - objects will be deleted immediately without grace period8882026/10/06 08:15:55 INFO Received uploads request method=POST path=/api/pending_closures8892026/10/06 08:15:55 INFO Vacuumed table table=pending_closures8902026/10/06 08:15:55 INFO Vacuumed table table=pending_objects8912026/10/06 08:15:55 INFO Vacuumed table table=multipart_uploads8922026/10/06 08:15:55 INFO Vacuumed table table=closures8932026/10/06 08:15:55 INFO Vacuumed table table=objects8942026/10/06 08:15:55 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"895--- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.48s)896=== CONT TestMetricsInventory8972026/10/06 08:15:55 ERROR failed to remove object object=nar/dddddddddddddddddddddddddddddddddddddddddddddddddddd.nar.zst error="Access Denied."8982026/10/06 08:15:55 ERROR failed to remove object object=dddddddddddddddddddddddddddddddd.narinfo error="Access Denied."8992026/10/06 08:15:55 INFO Aborted multipart uploads count=0 kept=0900--- PASS: TestFailedS3DeleteKeepsTombstone (1.19s)901=== CONT TestPinProtectsFromGC9022026/10/06 08:15:55 WARN Force mode enabled - objects will be deleted immediately without grace period9032026/10/06 08:15:55 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=09042026/10/06 08:15:55 INFO Aborted multipart uploads count=0 kept=09052026/10/06 08:15:55 INFO Vacuumed table table=pending_closures9062026/10/06 08:15:55 INFO Vacuumed table table=pending_objects9072026/10/06 08:15:55 INFO Vacuumed table table=multipart_uploads9082026/10/06 08:15:55 INFO Vacuumed table table=closures9092026/10/06 08:15:55 INFO Vacuumed table table=objects910--- PASS: TestSweepSkipsRowsLockedByPush (1.21s)911=== CONT TestNARDeduplicationMetadataUploadBug9122026/10/06 08:15:55 WARN Force mode enabled - objects will be deleted immediately without grace period9132026/10/06 08:15:55 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=09142026/10/06 08:15:55 INFO Vacuumed table table=pending_closures9152026/10/06 08:15:55 INFO Vacuumed table table=pending_objects9162026/10/06 08:15:55 INFO Vacuumed table table=multipart_uploads9172026/10/06 08:15:55 INFO Vacuumed table table=closures9182026/10/06 08:15:55 INFO Vacuumed table table=objects919--- PASS: TestGCMetrics (0.55s)920=== CONT TestCreatePendingClosureRejectsOversizedNAR9212026/10/06 08:15:55 INFO Received uploads request method=POST path=/api/pending_closures922--- PASS: TestCreatePendingClosureRejectsOversizedNAR (0.00s)923=== CONT TestClientSharedPathCommittedMidPush9242026/10/06 08:15:55 INFO lead: acquired remote=192.0.2.1:12349252026/10/06 08:15:55 INFO Aborted multipart uploads count=0 kept=09262026/10/06 08:15:55 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=09272026/10/06 08:15:55 INFO Vacuumed table table=pending_closures9282026/10/06 08:15:55 INFO Vacuumed table table=pending_objects9292026/10/06 08:15:55 INFO Vacuumed table table=multipart_uploads9302026/10/06 08:15:55 INFO Vacuumed table table=closures9312026/10/06 08:15:55 INFO Vacuumed table table=objects9322026/10/06 08:15:55 INFO Aborted multipart uploads count=0 kept=09332026/10/06 08:15:55 WARN Force mode enabled - objects will be deleted immediately without grace period9342026/10/06 08:15:55 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=09352026/10/06 08:15:55 INFO Vacuumed table table=pending_closures9362026/10/06 08:15:55 INFO Vacuumed table table=pending_objects9372026/10/06 08:15:55 INFO Vacuumed table table=multipart_uploads9382026/10/06 08:15:55 INFO Vacuumed table table=closures9392026/10/06 08:15:55 INFO Vacuumed table table=objects940--- PASS: TestPushDedupSurvivesConcurrentGC (1.26s)941=== CONT TestCacheConfigHandlerMaxNarSize942--- PASS: TestCacheConfigHandlerMaxNarSize (0.00s)943=== CONT TestClientWithDependencies9442026/10/06 08:15:55 INFO lead: acquired remote=192.0.2.1:12349452026/10/06 08:15:55 INFO lead: released remote=192.0.2.1:1234946--- PASS: TestLeadIncumbentAnnouncesAtOnce (0.49s)947=== CONT TestGenerateLandingPage948--- PASS: TestGenerateLandingPage (0.00s)949=== CONT TestService_readinessHandler950--- PASS: TestPresentReportsOnlyClosureRoots (0.54s)951=== CONT TestClientMultipleUploads9522026/10/06 08:15:55 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux9532026/10/06 08:15:55 WARN Refused reserved pin name=worker-x86_64-linux9542026/10/06 08:15:55 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux9552026/10/06 08:15:55 INFO Received create pin request method=POST path=/api/pins/my-app9562026/10/06 08:15:55 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux957--- PASS: TestCreatePin_ReservedPins (0.53s)958=== CONT TestService_healthCheckHandler9592026/10/06 08:15:55 INFO lead: acquired remote=192.0.2.1:12349602026/10/06 08:15:55 INFO Received uploads request method=POST path=/api/pending_closures961--- PASS: TestObjectStatsTrigger (0.50s)962=== CONT TestClientIntegration963--- PASS: TestGCBugBareHashReferences (0.76s)964=== CONT TestGracefulShutdownDrainsInflight9652026/10/06 08:15:55 INFO Starting HTTP server address=127.0.0.1:363379662026/10/06 08:15:55 INFO Shutdown signal received, draining in-flight requests timeout=10s9672026/10/06 08:15:55 INFO Received uploads request method=POST path=/api/pending_closures9682026/10/06 08:15:55 INFO Received cleanup request method=DELETE path=/api/pending_closures9692026/10/06 08:15:55 INFO Received uploads request method=POST path=/api/pending_closures970--- PASS: TestMultipartUploadAbortedWhenCancelledBeforeRecorded (0.46s)971=== CONT TestClientErrorHandling972=== RUN TestClientErrorHandling/InvalidStorePath973=== PAUSE TestClientErrorHandling/InvalidStorePath974=== RUN TestClientErrorHandling/InvalidAuthToken975=== PAUSE TestClientErrorHandling/InvalidAuthToken976=== RUN TestClientErrorHandling/ServerNotAvailable977=== PAUSE TestClientErrorHandling/ServerNotAvailable978=== CONT TestGCTaskStore_Fail979--- PASS: TestGCTaskStore_Fail (0.00s)980=== CONT TestGCTaskStore_PhaseUpdates981--- PASS: TestGCTaskStore_PhaseUpdates (0.00s)982=== CONT TestClientCADerivations983--- PASS: TestGracefulShutdownDrainsInflight (0.07s)984=== CONT TestGCTaskStore_CompletedAllowsNewTask985--- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)986=== CONT TestCacheStatsHandler9872026/10/06 08:15:55 INFO Received uploads request method=POST path=/api/pending_closures9882026/10/06 08:15:55 WARN mTLS auth: subject not in bound subjects subject="CN=reader"9892026/10/06 08:15:55 WARN mTLS auth: subject not in bound subjects subject="CN=reader"990--- PASS: TestService_NativeMTLS (0.47s)991=== CONT TestGCTaskStore_GetReturnsLatest992--- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)993=== CONT TestCacheConfigHandler994=== RUN TestCacheConfigHandler/full_config,_no_issuer995=== PAUSE TestCacheConfigHandler/full_config,_no_issuer996=== RUN TestCacheConfigHandler/no_cache_url_configured997=== PAUSE TestCacheConfigHandler/no_cache_url_configured998=== RUN TestCacheConfigHandler/no_signing_keys999=== PAUSE TestCacheConfigHandler/no_signing_keys1000=== RUN TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1001=== PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1002=== CONT TestGCTaskStore_GetEmpty1003--- PASS: TestGCTaskStore_GetEmpty (0.00s)1004=== CONT TestService_ReadScope_PublicByDefault10052026/10/06 08:15:55 INFO Received cleanup request method=DELETE path=/api/pending_closures10062026/10/06 08:15:55 WARN Failed to abort upload, keeping its closure key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst error="Get \"http://127.0.0.1:1/bucket41/?location=\": dial tcp 127.0.0.1:1: connect: connection refused" code=""10072026/10/06 08:15:55 INFO Aborted multipart uploads count=0 kept=110082026/10/06 08:15:55 INFO Received cleanup request method=DELETE path=/api/pending_closures10092026/10/06 08:15:55 INFO Aborted multipart uploads count=1 kept=01010--- PASS: TestMetricsInventory (0.46s)1011=== CONT TestGCTaskStore_ConflictDifferentParams1012--- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)1013=== CONT TestService_RequireScope_OIDC1014--- PASS: TestMultipartCleanup (0.57s)1015=== CONT TestGCTaskStore_DeduplicateSameParams1016--- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)1017=== CONT TestService_AuthMiddleware_OIDC10182026/10/06 08:15:55 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:33205/oidc1019=== NAME TestNARDeduplicationMetadataUploadBug1020 metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug2053448757/001/store/6b84lcqa604w9d8fwph9rif5kla7kvkx-file1.txt1021=== NAME TestOrphanedObjectsGC1022 orphaned_objects_gc_test.go:290: GC Test Summary:1023 orphaned_objects_gc_test.go:291: - Kept: 2 objects from closure A1024 orphaned_objects_gc_test.go:292: - Deleted: 2 objects from closure B1025 orphaned_objects_gc_test.go:293: - Deleted: 6 orphaned chain objects (X1->X2->X3)1026 orphaned_objects_gc_test.go:294: - Deleted: 2 orphaned single objects (Y)1027 orphaned_objects_gc_test.go:295: - Total deleted: 10 objects1028--- PASS: TestOrphanedObjectsGC (0.80s)1029=== CONT TestGCTaskStore_StartNew1030--- PASS: TestGCTaskStore_StartNew (0.00s)1031=== CONT TestService_ReadAuthMiddleware10322026/10/06 08:15:56 WARN readiness check failed error="closed pool"1033--- PASS: TestService_readinessHandler (0.43s)1034=== CONT TestGCEndsOnShutdown1035=== RUN TestGCEndsOnShutdown/before_the_run1036=== PAUSE TestGCEndsOnShutdown/before_the_run1037=== RUN TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete1038=== PAUSE TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete1039=== RUN TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark1040=== PAUSE TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark1041=== CONT TestService_AuthMiddleware_MTLSBoundSubjects1042=== NAME TestPinProtectsFromGC1043 client_integration_test.go:731: Pinned store path: /build/TestPinProtectsFromGC798678934/001/store/8zd2m4z51340nkd325f4kjy10vcbn97b-pinned-file.txt1044 client_integration_test.go:732: Unpinned store path: /build/TestPinProtectsFromGC798678934/001/store/k3782fj93m77n5kydnc33rxrvl1wm17n-unpinned-file.txt1045--- PASS: TestService_healthCheckHandler (0.42s)1046=== CONT TestCommitRacingPendingCleanupKeepsObjects1047--- PASS: TestPresentNotReportedWhileGCDeletesClosure (1.04s)1048=== CONT TestService_AuthMiddleware_MTLSProxyHeader10492026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures10502026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes10512026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures10522026/10/06 08:15:56 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)10532026/10/06 08:15:56 INFO Uploading 6b84lcqa604w9d8fwph9rif5kla7kvkx-file1.txt (160B)10542026/10/06 08:15:56 INFO Uploading 2 paths to 127.0.0.1 (1 already cached)10552026/10/06 08:15:56 INFO Uploading x3qwcyhm1axchyq5vjkxk20d9rijplcy-shared-dep (136B)10562026/10/06 08:15:56 INFO Uploading 8nb4zlzrah7hvsfb3hnf4i58p9ji79ml-a (216B)10572026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes1058=== NAME TestClientMultipleUploads1059 client_integration_test.go:358: Created store path 0: /build/TestClientMultipleUploads1471444578/001/store/4960myy5xj3c8afcai3aqdmxs7w31d6p-test-file-0.txt10602026/10/06 08:15:56 WARN Failed to register uploaded object key=0p9d5nm9shr23ybafx7xx2gwz9z6v84v.ls error="server returned 404: 404 page not found\n"10612026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst error="server returned 404: 404 page not found\n"10622026/10/06 08:15:56 WARN Failed to register uploaded object key=6b84lcqa604w9d8fwph9rif5kla7kvkx.ls error="server returned 404: 404 page not found\n"10632026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign10642026/10/06 08:15:56 WARN Failed to register uploaded object key=x3qwcyhm1axchyq5vjkxk20d9rijplcy.ls error="server returned 404: 404 page not found\n"10652026/10/06 08:15:56 WARN Failed to register uploaded object key=8nb4zlzrah7hvsfb3hnf4i58p9ji79ml.ls error="server returned 404: 404 page not found\n"10662026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"10672026/10/06 08:15:56 INFO Signed narinfos id=1 count=110682026/10/06 08:15:56 INFO Uploading 1 narinfos10692026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign10702026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/1iy86d8gwbqdf9ka3q7bx98yip7h63s6wyx16mqpw2ijpfbf496n.nar.zst error="server returned 404: 404 page not found\n"10712026/10/06 08:15:56 INFO Signed narinfos id=1 count=210722026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign10732026/10/06 08:15:56 INFO Signed narinfos id=2 count=210742026/10/06 08:15:56 INFO Uploading 4 narinfos10752026/10/06 08:15:56 INFO Uploading 2 paths to 127.0.0.1 (1 already cached)10762026/10/06 08:15:56 INFO Uploading lbrq0nmaz2kys4hczrv2xv92prb5hfbm-shared-dep (136B)10772026/10/06 08:15:56 INFO Uploading iakci2mlpslbz73ch1mc8vzh5f17g2p4-b (216B)10782026/10/06 08:15:56 INFO Received complete push request method=POST path=/api/pushes/1/complete10792026/10/06 08:15:56 WARN Failed to register uploaded object key=6b84lcqa604w9d8fwph9rif5kla7kvkx.narinfo error="server returned 404: 404 page not found\n"10802026/10/06 08:15:56 WARN Failed to register uploaded object key=x3qwcyhm1axchyq5vjkxk20d9rijplcy.narinfo error="server returned 404: 404 page not found\n"10812026/10/06 08:15:56 WARN Failed to register uploaded object key=0p9d5nm9shr23ybafx7xx2gwz9z6v84v.narinfo error="server returned 404: 404 page not found\n"10822026/10/06 08:15:56 WARN Failed to register uploaded object key=8nb4zlzrah7hvsfb3hnf4i58p9ji79ml.narinfo error="server returned 404: 404 page not found\n"10832026/10/06 08:15:56 WARN Failed to register uploaded object key=lbrq0nmaz2kys4hczrv2xv92prb5hfbm.ls error="server returned 404: 404 page not found\n"10842026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/0r9rhrzqpni53d52vmmfrlmqf4rakg8ccncy4k22iaiswm5szn0w.nar.zst error="server returned 404: 404 page not found\n"10852026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"10862026/10/06 08:15:56 WARN Failed to register uploaded object key=mhhyp0p346kn5af1by5d13dhis4h501s.ls error="server returned 404: 404 page not found\n"10872026/10/06 08:15:56 WARN Failed to register uploaded object key=iakci2mlpslbz73ch1mc8vzh5f17g2p4.ls error="server returned 404: 404 page not found\n"10882026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign10892026/10/06 08:15:56 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete10902026/10/06 08:15:56 WARN Failed to register uploaded object key=x3qwcyhm1axchyq5vjkxk20d9rijplcy.narinfo error="server returned 404: 404 page not found\n"10912026/10/06 08:15:56 INFO Signed narinfos id=1 count=310922026/10/06 08:15:56 INFO Uploading 3 narinfos1093=== NAME TestClientWithDependencies1094 client_integration_test.go:613: Built derivation: /build/TestClientWithDependencies1421358604/001/store/7h86jfqwni8s5yf0c81qvxnzakvjlpdv-test-script10952026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes10962026/10/06 08:15:56 WARN Failed to register uploaded object key=lbrq0nmaz2kys4hczrv2xv92prb5hfbm.narinfo error="server returned 404: 404 page not found\n"10972026/10/06 08:15:56 WARN Failed to register uploaded object key=mhhyp0p346kn5af1by5d13dhis4h501s.narinfo error="server returned 404: 404 page not found\n"10982026/10/06 08:15:56 INFO Received complete push request method=POST path=/api/pushes/1/complete10992026/10/06 08:15:56 WARN Failed to register uploaded object key=iakci2mlpslbz73ch1mc8vzh5f17g2p4.narinfo error="server returned 404: 404 page not found\n"11002026/10/06 08:15:56 INFO Completed upload id=111012026/10/06 08:15:56 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete11022026/10/06 08:15:56 INFO Completed upload id=211032026/10/06 08:15:56 INFO Upload complete. (68ms)11042026/10/06 08:15:56 INFO Upload complete. (69ms)1105=== NAME TestClientFallsBackToClosures1106 client_pushes_test.go:112: Retrieved narinfo from S3:1107 StorePath: /build/TestClientFallsBackToClosures1440572085/001/store/x3qwcyhm1axchyq5vjkxk20d9rijplcy-shared-dep1108 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1109 Compression: zstd1110 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821111 NarSize: 1361112 References: 1113 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n11142026/10/06 08:15:56 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1115=== NAME TestNARDeduplicationMetadataUploadBug11162026/10/06 08:15:56 INFO Uploading 8zd2m4z51340nkd325f4kjy10vcbn97b-pinned-file.txt (128B)1117 metadata_upload_test.go:54: Retrieved narinfo from S3:1118 StorePath: /build/TestNARDeduplicationMetadataUploadBug2053448757/001/store/6b84lcqa604w9d8fwph9rif5kla7kvkx-file1.txt1119 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1120 Compression: zstd1121 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1122 NarSize: 1601123 References: 1124 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1125=== NAME TestClientFallsBackToClosures1126 client_pushes_test.go:112: Retrieved narinfo from S3:1127 StorePath: /build/TestClientFallsBackToClosures1440572085/001/store/8nb4zlzrah7hvsfb3hnf4i58p9ji79ml-a1128 URL: nar/1iy86d8gwbqdf9ka3q7bx98yip7h63s6wyx16mqpw2ijpfbf496n.nar.zst1129 Compression: zstd1130 NarHash: sha256:1iy86d8gwbqdf9ka3q7bx98yip7h63s6wyx16mqpw2ijpfbf496n1131 NarSize: 2161132 References: /build/TestClientFallsBackToClosures1440572085/001/store/x3qwcyhm1axchyq5vjkxk20d9rijplcy-shared-dep1133 CA: text:sha256:0qca0p8iawbgy0wb811l5kr6yy073ddc4shcza5dq7agqwk1c3141134=== NAME TestNARDeduplicationMetadataUploadBug1135 metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1136 metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1137 {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1138--- PASS: TestConcurrentCommitsSharingObjectsDoNotDeadlock (0.74s)1139=== CONT TestPushWaitsForSweepHoldingRow11402026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/0qmhsg42pqsz7x6hh02j8gznnac3yi3qpjhrqbwvz88i194jbb5s.nar.zst error="server returned 404: 404 page not found\n"11412026/10/06 08:15:56 INFO Upload complete. (67ms)1142=== NAME TestClientFallsBackToClosures1143 client_pushes_test.go:112: Retrieved narinfo from S3:1144 StorePath: /build/TestClientFallsBackToClosures1440572085/001/store/0p9d5nm9shr23ybafx7xx2gwz9z6v84v-b11452026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign1146 URL: nar/1iy86d8gwbqdf9ka3q7bx98yip7h63s6wyx16mqpw2ijpfbf496n.nar.zst1147 Compression: zstd1148 NarHash: sha256:1iy86d8gwbqdf9ka3q7bx98yip7h63s6wyx16mqpw2ijpfbf496n1149 NarSize: 2161150 References: /build/TestClientFallsBackToClosures1440572085/001/store/x3qwcyhm1axchyq5vjkxk20d9rijplcy-shared-dep1151 CA: text:sha256:0qca0p8iawbgy0wb811l5kr6yy073ddc4shcza5dq7agqwk1c31411522026/10/06 08:15:56 INFO Signed narinfos id=1 count=11153=== NAME TestClientPushesUseOnePush11542026/10/06 08:15:56 WARN Failed to register uploaded object key=8zd2m4z51340nkd325f4kjy10vcbn97b.ls error="server returned 404: 404 page not found\n"1155 client_pushes_test.go:97: Retrieved narinfo from S3:11562026/10/06 08:15:56 INFO Uploading 1 narinfos1157 StorePath: /build/TestClientPushesUseOnePush1937738626/001/store/lbrq0nmaz2kys4hczrv2xv92prb5hfbm-shared-dep1158 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1159 Compression: zstd1160 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821161 NarSize: 1361162 References: 1163 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1164 client_pushes_test.go:97: Retrieved narinfo from S3:1165 StorePath: /build/TestClientPushesUseOnePush1937738626/001/store/mhhyp0p346kn5af1by5d13dhis4h501s-a1166 URL: nar/0r9rhrzqpni53d52vmmfrlmqf4rakg8ccncy4k22iaiswm5szn0w.nar.zst1167 Compression: zstd1168 NarHash: sha256:0r9rhrzqpni53d52vmmfrlmqf4rakg8ccncy4k22iaiswm5szn0w1169 NarSize: 2161170 References: /build/TestClientPushesUseOnePush1937738626/001/store/lbrq0nmaz2kys4hczrv2xv92prb5hfbm-shared-dep1171 CA: text:sha256:1c4zjjjm3ljxqnyzx1a8axs7bjanyqhrxhac8v5m0jccwdf83fmh11722026/10/06 08:15:56 INFO Received complete push request method=POST path=/api/pushes/1/complete11732026/10/06 08:15:56 WARN Failed to register uploaded object key=8zd2m4z51340nkd325f4kjy10vcbn97b.narinfo error="server returned 404: 404 page not found\n"1174--- PASS: TestClientFallsBackToClosures (0.74s)1175=== CONT TestParseSize1176--- PASS: TestParseSize (0.00s)1177=== CONT TestCreatePendingClosure_SmallNARUsesSimplePUT1178=== NAME TestClientPushesUseOnePush1179 client_pushes_test.go:97: Retrieved narinfo from S3:1180 StorePath: /build/TestClientPushesUseOnePush1937738626/001/store/iakci2mlpslbz73ch1mc8vzh5f17g2p4-b1181 URL: nar/0r9rhrzqpni53d52vmmfrlmqf4rakg8ccncy4k22iaiswm5szn0w.nar.zst1182 Compression: zstd1183 NarHash: sha256:0r9rhrzqpni53d52vmmfrlmqf4rakg8ccncy4k22iaiswm5szn0w1184 NarSize: 2161185 References: /build/TestClientPushesUseOnePush1937738626/001/store/lbrq0nmaz2kys4hczrv2xv92prb5hfbm-shared-dep1186 CA: text:sha256:1c4zjjjm3ljxqnyzx1a8axs7bjanyqhrxhac8v5m0jccwdf83fmh1187--- PASS: TestClientPushesUseOnePush (0.71s)1188=== CONT TestCompleteMultipartUnregistered1189=== NAME TestClientMultipleUploads1190 client_integration_test.go:358: Created store path 1: /build/TestClientMultipleUploads1471444578/001/store/6b06nq74kyyv5a6cda323h15yg3n8afd-test-file-1.txt1191=== NAME TestClientIntegration1192 client_integration_test.go:286: Created store path: /build/TestClientIntegration2087843308/002/store/mrvj4qkwdcnqqbsbx9z7hymq7mki7lwv-test-file.txt11932026/10/06 08:15:56 INFO Upload complete. (70ms)1194=== NAME TestClientWithDependencies1195 client_integration_test.go:615: Found 1 dependencies (including self)1196--- PASS: TestCacheStatsHandler (0.33s)1197=== CONT TestService_verifyS3Integrity11982026/10/06 08:15:56 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:41421/oidc11992026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes1200=== NAME TestNARDeduplicationMetadataUploadBug1201 metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug2053448757/001/store/d542vhsz0zd62xl71q1c5laxyd340f73-file2.txt1202--- PASS: TestService_ReadScope_PublicByDefault (0.30s)1203=== CONT TestService_createPendingClosureHandler12042026/10/06 08:15:56 INFO Uploading 2 paths to 127.0.0.1 (0 already cached)12052026/10/06 08:15:56 INFO Uploading 9176nv2lkszq9dk8iv4mmicnq2ps2x8p-shared-dep (136B)12062026/10/06 08:15:56 INFO Uploading ab99s0c0aiyjlq2jqxxy1ryiiahsw9z0-top (224B)1207=== NAME TestClientMultipleUploads1208 client_integration_test.go:358: Created store path 2: /build/TestClientMultipleUploads1471444578/001/store/npqswik90pwryb7kbf5g6xybhppppy00-test-file-2.txt12092026/10/06 08:15:56 WARN Failed to register uploaded object key=9176nv2lkszq9dk8iv4mmicnq2ps2x8p.ls error="server returned 404: 404 page not found\n"12102026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/1v7zss09agcqpaxrksqnf80vmkij610ra66xjmckbl1cdpf1yh5x.nar.zst error="server returned 404: 404 page not found\n"12112026/10/06 08:15:56 WARN Failed to register uploaded object key=ab99s0c0aiyjlq2jqxxy1ryiiahsw9z0.ls error="server returned 404: 404 page not found\n"12122026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign12132026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"12142026/10/06 08:15:56 INFO Signed narinfos id=1 count=212152026/10/06 08:15:56 INFO Uploading 2 narinfos12162026/10/06 08:15:56 WARN Failed to register uploaded object key=9176nv2lkszq9dk8iv4mmicnq2ps2x8p.narinfo error="server returned 404: 404 page not found\n"12172026/10/06 08:15:56 INFO Received complete push request method=POST path=/api/pushes/1/complete12182026/10/06 08:15:56 WARN Failed to register uploaded object key=ab99s0c0aiyjlq2jqxxy1ryiiahsw9z0.narinfo error="server returned 404: 404 page not found\n"12192026/10/06 08:15:56 INFO Upload complete. (78ms)1220=== NAME TestClientSharedPathCommittedMidPush1221 client_integration_test.go:680: Retrieved narinfo from S3:1222 StorePath: /build/TestClientSharedPathCommittedMidPush649911497/001/store/9176nv2lkszq9dk8iv4mmicnq2ps2x8p-shared-dep1223 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1224 Compression: zstd1225 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821226 NarSize: 1361227 References: 1228 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1229 client_integration_test.go:680: Retrieved narinfo from S3:1230 StorePath: /build/TestClientSharedPathCommittedMidPush649911497/001/store/ab99s0c0aiyjlq2jqxxy1ryiiahsw9z0-top1231 URL: nar/1v7zss09agcqpaxrksqnf80vmkij610ra66xjmckbl1cdpf1yh5x.nar.zst1232 Compression: zstd1233 NarHash: sha256:1v7zss09agcqpaxrksqnf80vmkij610ra66xjmckbl1cdpf1yh5x1234 NarSize: 2241235 References: /build/TestClientSharedPathCommittedMidPush649911497/001/store/9176nv2lkszq9dk8iv4mmicnq2ps2x8p-shared-dep1236 CA: text:sha256:1h51n17mfq4javv233n8rsmfrhhx2n1pmn0w4v2fg0f7k0aa18b51237--- PASS: TestClientSharedPathCommittedMidPush (0.67s)1238=== CONT TestService_cleanupPendingClosuresHandler1239=== RUN TestService_RequireScope_OIDC/builder_may_write1240=== PAUSE TestService_RequireScope_OIDC/builder_may_write1241=== RUN TestService_RequireScope_OIDC/builder_may_not_admin1242=== PAUSE TestService_RequireScope_OIDC/builder_may_not_admin1243=== RUN TestService_RequireScope_OIDC/ops_may_admin1244=== PAUSE TestService_RequireScope_OIDC/ops_may_admin1245=== RUN TestService_RequireScope_OIDC/ops_may_not_write1246=== PAUSE TestService_RequireScope_OIDC/ops_may_not_write1247=== RUN TestService_RequireScope_OIDC/reader_may_not_write1248=== PAUSE TestService_RequireScope_OIDC/reader_may_not_write1249=== RUN TestService_RequireScope_OIDC/static_token_may_admin1250=== PAUSE TestService_RequireScope_OIDC/static_token_may_admin1251=== RUN TestService_RequireScope_OIDC/static_token_may_write1252=== PAUSE TestService_RequireScope_OIDC/static_token_may_write1253=== RUN TestService_RequireScope_OIDC/reader_may_read1254=== PAUSE TestService_RequireScope_OIDC/reader_may_read1255=== RUN TestService_RequireScope_OIDC/writer_implies_read1256=== PAUSE TestService_RequireScope_OIDC/writer_implies_read1257=== RUN TestService_RequireScope_OIDC/anonymous_may_not_read1258=== PAUSE TestService_RequireScope_OIDC/anonymous_may_not_read1259=== RUN TestService_RequireScope_OIDC/reader_may_read_with_netrc1260=== PAUSE TestService_RequireScope_OIDC/reader_may_read_with_netrc1261=== RUN TestService_RequireScope_OIDC/static_token_may_admin_with_netrc1262=== PAUSE TestService_RequireScope_OIDC/static_token_may_admin_with_netrc1263=== RUN TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read1264=== PAUSE TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read1265=== RUN TestService_RequireScope_OIDC/empty_netrc_password_may_not_read1266=== PAUSE TestService_RequireScope_OIDC/empty_netrc_password_may_not_read1267=== CONT TestUploadHandlersRejectOversizedBody12682026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes1269=== NAME TestClientCADerivations1270 client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations2028460159/001/store/9rn36qg4jmjn4f6ffknd344dycj190s3-ca-test12712026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes12722026/10/06 08:15:56 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)12732026/10/06 08:15:56 INFO Uploading k3782fj93m77n5kydnc33rxrvl1wm17n-unpinned-file.txt (128B)12742026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes12752026/10/06 08:15:56 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1276 client_ca_test.go:139: Found 1 dependencies (including self)12772026/10/06 08:15:56 INFO Uploading mrvj4qkwdcnqqbsbx9z7hymq7mki7lwv-test-file.txt (152B)1278--- PASS: TestService_ReadAuthMiddleware (0.27s)12792026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes1280=== CONT TestUploadHandlersRejectInvalidKeys12812026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes1282=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1283=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info1284=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1285=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal1286=== RUN TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1287=== PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key1288=== RUN TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1289=== PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key1290=== CONT TestIsValidUploadKey1291=== RUN TestIsValidUploadKey/narinfo1292=== PAUSE TestIsValidUploadKey/narinfo1293=== RUN TestIsValidUploadKey/nar_zst1294=== PAUSE TestIsValidUploadKey/nar_zst1295=== RUN TestIsValidUploadKey/nar_xz1296=== PAUSE TestIsValidUploadKey/nar_xz1297=== RUN TestIsValidUploadKey/nar_plain1298=== PAUSE TestIsValidUploadKey/nar_plain1299=== RUN TestIsValidUploadKey/listing1300=== PAUSE TestIsValidUploadKey/listing1301=== RUN TestIsValidUploadKey/build_log1302=== PAUSE TestIsValidUploadKey/build_log1303=== RUN TestIsValidUploadKey/build_log_home-manager_file1304=== PAUSE TestIsValidUploadKey/build_log_home-manager_file1305=== RUN TestIsValidUploadKey/build_log_plus_in_name1306=== PAUSE TestIsValidUploadKey/build_log_plus_in_name1307=== RUN TestIsValidUploadKey/build_log_question_mark1308=== PAUSE TestIsValidUploadKey/build_log_question_mark1309=== RUN TestIsValidUploadKey/build_log_equals1310=== PAUSE TestIsValidUploadKey/build_log_equals1311=== RUN TestIsValidUploadKey/realisation1312=== PAUSE TestIsValidUploadKey/realisation1313=== RUN TestIsValidUploadKey/realisation_plus_in_output1314=== PAUSE TestIsValidUploadKey/realisation_plus_in_output1315=== RUN TestIsValidUploadKey/nix-cache-info1316=== PAUSE TestIsValidUploadKey/nix-cache-info1317=== RUN TestIsValidUploadKey/index.html1318=== PAUSE TestIsValidUploadKey/index.html1319=== RUN TestIsValidUploadKey/narinfo_key,_nar_type1320=== PAUSE TestIsValidUploadKey/narinfo_key,_nar_type1321=== RUN TestIsValidUploadKey/nar_key,_narinfo_type1322=== PAUSE TestIsValidUploadKey/nar_key,_narinfo_type1323=== RUN TestIsValidUploadKey/listing_key,_narinfo_type1324=== PAUSE TestIsValidUploadKey/listing_key,_narinfo_type1325=== RUN TestIsValidUploadKey/traversal13262026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/0gh0wdnr6gmk4r5sfzzh12isnl14rgmkrwy1ws41hpsrlndclg5h.nar.zst error="server returned 404: 404 page not found\n"1327=== PAUSE TestIsValidUploadKey/traversal1328=== RUN TestIsValidUploadKey/traversal_nar1329=== PAUSE TestIsValidUploadKey/traversal_nar1330=== RUN TestIsValidUploadKey/absolute1331=== PAUSE TestIsValidUploadKey/absolute1332=== RUN TestIsValidUploadKey/empty_key1333=== PAUSE TestIsValidUploadKey/empty_key1334=== RUN TestIsValidUploadKey/unknown_type13352026/10/06 08:15:56 WARN Failed to register uploaded object key=mrvj4qkwdcnqqbsbx9z7hymq7mki7lwv.ls error="server returned 404: 404 page not found\n"1336=== PAUSE TestIsValidUploadKey/unknown_type1337=== CONT TestPendingClosureWriteTimeout1338=== RUN TestPendingClosureWriteTimeout/empty13392026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign13402026/10/06 08:15:56 INFO Signed narinfos id=1 count=113412026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign13422026/10/06 08:15:56 INFO Uploading 1 narinfos13432026/10/06 08:15:56 INFO Signed narinfos id=2 count=113442026/10/06 08:15:56 INFO Uploading 1 narinfos1345=== PAUSE TestPendingClosureWriteTimeout/empty13462026/10/06 08:15:56 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)1347=== RUN TestPendingClosureWriteTimeout/400_objects1348=== PAUSE TestPendingClosureWriteTimeout/400_objects1349=== RUN TestPendingClosureWriteTimeout/670k_objects1350=== PAUSE TestPendingClosureWriteTimeout/670k_objects1351=== RUN TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline13522026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst error="server returned 404: 404 page not found\n"1353=== PAUSE TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline13542026/10/06 08:15:56 WARN Failed to register uploaded object key=k3782fj93m77n5kydnc33rxrvl1wm17n.ls error="server returned 404: 404 page not found\n"1355=== CONT TestProxyWriteTimeout1356=== RUN TestUploadHandlersRejectOversizedBody/complete_multipart13572026/10/06 08:15:56 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"1358=== RUN TestProxyWriteTimeout/narinfo13592026/10/06 08:15:56 WARN mTLS auth: bound subjects configured but subject DN unavailable1360=== PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart13612026/10/06 08:15:56 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1362=== PAUSE TestProxyWriteTimeout/narinfo1363=== RUN TestUploadHandlersRejectOversizedBody/request_more_parts1364=== PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts1365--- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.36s)1366=== RUN TestProxyWriteTimeout/1_GiB_nar1367=== PAUSE TestProxyWriteTimeout/1_GiB_nar1368=== RUN TestProxyWriteTimeout/10_GiB_nar13692026/10/06 08:15:56 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)1370=== PAUSE TestProxyWriteTimeout/10_GiB_nar1371=== RUN TestProxyWriteTimeout/unknown_size13722026/10/06 08:15:56 INFO Uploading 7h86jfqwni8s5yf0c81qvxnzakvjlpdv-test-script (136B)1373=== PAUSE TestProxyWriteTimeout/unknown_size1374=== CONT TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1375=== RUN TestUploadHandlersRejectOversizedBody/create_pending_closure1376=== PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure1377=== CONT TestSkippedUploadsHandler1378=== CONT TestPush_RejectsBadRequests13792026/10/06 08:15:56 INFO Client skipped oversized paths paths=3 nar_bytes=500000000013802026/10/06 08:15:56 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)13812026/10/06 08:15:56 INFO Uploading 4960myy5xj3c8afcai3aqdmxs7w31d6p-test-file-0.txt (160B)13822026/10/06 08:15:56 INFO Uploading npqswik90pwryb7kbf5g6xybhppppy00-test-file-2.txt (160B)13832026/10/06 08:15:56 INFO Uploading 6b06nq74kyyv5a6cda323h15yg3n8afd-test-file-1.txt (160B)13842026/10/06 08:15:56 INFO Received complete push request method=POST path=/api/pushes/2/complete13852026/10/06 08:15:56 INFO Received complete push request method=POST path=/api/pushes/1/complete13862026/10/06 08:15:56 WARN Failed to register uploaded object key=k3782fj93m77n5kydnc33rxrvl1wm17n.narinfo error="server returned 404: 404 page not found\n"13872026/10/06 08:15:56 WARN Failed to register uploaded object key=mrvj4qkwdcnqqbsbx9z7hymq7mki7lwv.narinfo error="server returned 404: 404 page not found\n"13882026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign13892026/10/06 08:15:56 WARN Failed to register uploaded object key=d542vhsz0zd62xl71q1c5laxyd340f73.ls error="server returned 404: 404 page not found\n"13902026/10/06 08:15:56 INFO Signed narinfos id=2 count=113912026/10/06 08:15:56 INFO Upload complete. (226ms)13922026/10/06 08:15:56 INFO Uploading 1 narinfos13932026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/1s7yghixzpinyqm7g59kyn3sav81pmnsdi5v63m6x4r2vacy907j.nar.zst error="server returned 404: 404 page not found\n"13942026/10/06 08:15:56 WARN Failed to register uploaded object key=7h86jfqwni8s5yf0c81qvxnzakvjlpdv.ls error="server returned 404: 404 page not found\n"13952026/10/06 08:15:56 WARN Failed to register uploaded object key=log/lvsnpaxgsmfda02ajyb7b2i5nc7mjmgv-test-script.drv error="server returned 404: 404 page not found\n"13962026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign13972026/10/06 08:15:56 INFO Signed narinfos id=1 count=113982026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/1823g6vx4q5zqvr73m7lkq1wpk40fin7bsc792di430yk7sg4lka.nar.zst error="server returned 404: 404 page not found\n"13992026/10/06 08:15:56 INFO Uploading 1 narinfos14002026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/0ahq3izw3h0b0c08p3xdfclh758fwxnp85ccvflasb4vnfsv50ri.nar.zst error="server returned 404: 404 page not found\n"14012026/10/06 08:15:56 WARN Failed to register uploaded object key=npqswik90pwryb7kbf5g6xybhppppy00.ls error="server returned 404: 404 page not found\n"14022026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/13p9pqi0ga6fx2apcwls9hjnv7nmf740ggpn5zvfzdsp5vz2bbg4.nar.zst error="server returned 404: 404 page not found\n"14032026/10/06 08:15:56 WARN Failed to register uploaded object key=4960myy5xj3c8afcai3aqdmxs7w31d6p.ls error="server returned 404: 404 page not found\n"14042026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign14052026/10/06 08:15:56 WARN Failed to register uploaded object key=6b06nq74kyyv5a6cda323h15yg3n8afd.ls error="server returned 404: 404 page not found\n"1406--- PASS: TestSkippedUploadsHandler (0.01s)14072026/10/06 08:15:56 INFO Received complete push request method=POST path=/api/pushes/2/complete1408=== CONT TestService_Rustfstest14092026/10/06 08:15:56 WARN Failed to register uploaded object key=d542vhsz0zd62xl71q1c5laxyd340f73.narinfo error="server returned 404: 404 page not found\n"14102026/10/06 08:15:56 INFO Signed narinfos id=1 count=314112026/10/06 08:15:56 INFO Uploading 3 narinfos14122026/10/06 08:15:56 INFO Upload complete. (233ms)14132026/10/06 08:15:56 INFO Received complete push request method=POST path=/api/pushes/1/complete14142026/10/06 08:15:56 WARN Failed to register uploaded object key=7h86jfqwni8s5yf0c81qvxnzakvjlpdv.narinfo error="server returned 404: 404 page not found\n"14152026/10/06 08:15:56 INFO Upload complete. (210ms)1416=== NAME TestNARDeduplicationMetadataUploadBug1417 metadata_upload_test.go:76: Retrieved narinfo from S3:1418 StorePath: /build/TestNARDeduplicationMetadataUploadBug2053448757/001/store/d542vhsz0zd62xl71q1c5laxyd340f73-file2.txt14192026/10/06 08:15:56 WARN Failed to register uploaded object key=6b06nq74kyyv5a6cda323h15yg3n8afd.narinfo error="server returned 404: 404 page not found\n"1420 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst14212026/10/06 08:15:56 WARN Failed to register uploaded object key=4960myy5xj3c8afcai3aqdmxs7w31d6p.narinfo error="server returned 404: 404 page not found\n"1422 Compression: zstd14232026/10/06 08:15:56 INFO Received complete push request method=POST path=/api/pushes/1/complete1424 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf14252026/10/06 08:15:56 WARN Failed to register uploaded object key=npqswik90pwryb7kbf5g6xybhppppy00.narinfo error="server returned 404: 404 page not found\n"1426 NarSize: 1601427 References: 1428 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1429 metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1430 metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1431 {"version":1,"root":{"type":"regular","size":44}}14322026/10/06 08:15:56 INFO Upload complete. (244ms)1433--- PASS: TestNARDeduplicationMetadataUploadBug (0.91s)1434=== CONT TestPresignedUploadRegisteredBeforeCommit1435=== NAME TestClientWithDependencies1436 client_integration_test.go:617: Skipping nix copy test - isolated store (/build/TestClientWithDependencies1421358604/001/store) requires matching store prefix14372026/10/06 08:15:56 INFO Upload complete. (209ms)1438=== NAME TestClientMultipleUploads1439 client_integration_test.go:369: Uploaded 3 paths in 246.886684ms1440--- PASS: TestClientWithDependencies (0.85s)1441=== CONT TestCompletedNarNotReofferedAcrossClosures1442--- PASS: TestClientMultipleUploads (0.82s)1443=== CONT TestCompleteMultipartUpload_ErrorButObjectExists14442026/10/06 08:15:56 INFO Starting cleanup of old closures method=DELETE path=/api/closures14452026/10/06 08:15:56 INFO Garbage collection started14462026/10/06 08:15:56 INFO All 1 paths already cached1447=== NAME TestClientIntegration1448 client_integration_test.go:312: Retrieved narinfo from S3:1449 StorePath: /build/TestClientIntegration2087843308/002/store/mrvj4qkwdcnqqbsbx9z7hymq7mki7lwv-test-file.txt1450 URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1451 Compression: zstd1452 NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11453 NarSize: 1521454 References: 1455 CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11456 client_integration_test.go:313: Retrieved .ls file from S3 (compressed size: 77 bytes)1457 client_integration_test.go:313: Decompressed .ls content (64 bytes):1458 {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1459 client_integration_test.go:316: Testing garbage collection...14602026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes14612026/10/06 08:15:56 INFO Aborted multipart uploads count=0 kept=014622026/10/06 08:15:56 WARN Force mode enabled - objects will be deleted immediately without grace period14632026/10/06 08:15:56 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14642026/10/06 08:15:56 INFO Uploading 9rn36qg4jmjn4f6ffknd344dycj190s3-ca-test (144B)14652026/10/06 08:15:56 WARN Failed to register uploaded object key=log/qixqpvhwlqnd757bxwsrjbcaagwmxb7w-ca-test.drv error="server returned 404: 404 page not found\n"14662026/10/06 08:15:56 WARN Failed to register uploaded object key=nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst error="server returned 404: 404 page not found\n"14672026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign14682026/10/06 08:15:56 WARN Failed to register uploaded object key=9rn36qg4jmjn4f6ffknd344dycj190s3.ls error="server returned 404: 404 page not found\n"14692026/10/06 08:15:56 INFO Signed narinfos id=1 count=114702026/10/06 08:15:56 INFO Uploading 1 narinfos1471--- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.40s)1472=== CONT TestRedundantMultipartUpload14732026/10/06 08:15:56 INFO Received complete push request method=POST path=/api/pushes/1/complete14742026/10/06 08:15:56 WARN Failed to register uploaded object key=9rn36qg4jmjn4f6ffknd344dycj190s3.narinfo error="server returned 404: 404 page not found\n"14752026/10/06 08:15:56 INFO Upload complete. (206ms)14762026/10/06 08:15:56 INFO Starting cleanup of old closures method=DELETE path=/api/closures14772026/10/06 08:15:56 INFO Garbage collection started1478=== NAME TestClientCADerivations1479 client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations2028460159/001/store/9rn36qg4jmjn4f6ffknd344dycj190s3-ca-test1480 URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1481 Compression: zstd1482 NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1483 NarSize: 1441484 References: 1485 Deriver: /build/TestClientCADerivations2028460159/001/store/qixqpvhwlqnd757bxwsrjbcaagwmxb7w-ca-test.drv1486 CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1487 client_ca_test.go:185: Checking for realisation files in S3...14882026/10/06 08:15:56 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=01489 client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1490 client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache14912026/10/06 08:15:56 INFO Aborted multipart uploads count=0 kept=014922026/10/06 08:15:56 WARN Force mode enabled - objects will be deleted immediately without grace period14932026/10/06 08:15:56 INFO Aborted multipart uploads count=0 kept=014942026/10/06 08:15:56 INFO Vacuumed table table=pending_closures14952026/10/06 08:15:56 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=014962026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures14972026/10/06 08:15:56 WARN Force mode enabled - objects will be deleted immediately without grace period14982026/10/06 08:15:56 INFO Vacuumed table table=pending_closures14992026/10/06 08:15:56 INFO Vacuumed table table=pending_objects15002026/10/06 08:15:56 INFO Vacuumed table table=multipart_uploads15012026/10/06 08:15:56 INFO Vacuumed table table=pending_objects15022026/10/06 08:15:56 INFO Vacuumed table table=multipart_uploads15032026/10/06 08:15:56 INFO Vacuumed table table=closures15042026/10/06 08:15:56 INFO Vacuumed table table=objects15052026/10/06 08:15:56 INFO Vacuumed table table=closures15062026/10/06 08:15:56 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=015072026/10/06 08:15:56 INFO lead: released remote=192.0.2.1:123415082026/10/06 08:15:56 INFO Vacuumed table table=objects1509--- PASS: TestLeadEndsOnShutdown (1.55s)1510=== CONT TestPush_SignsNarinfosOfItsPendingObjects1511--- PASS: TestCommitRacingPendingCleanupKeepsObjects (0.52s)1512=== CONT TestPush_OverlappingRootsStoreOneRowPerKey15132026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures15142026/10/06 08:15:56 INFO Vacuumed table table=pending_closures15152026/10/06 08:15:56 INFO Vacuumed table table=pending_objects15162026/10/06 08:15:56 INFO Vacuumed table table=multipart_uploads15172026/10/06 08:15:56 INFO Vacuumed table table=closures15182026/10/06 08:15:56 INFO Vacuumed table table=objects15192026/10/06 08:15:56 INFO Received cleanup request method=DELETE path=/api/pending_closures15202026/10/06 08:15:56 INFO Aborted multipart uploads count=0 kept=015212026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures1522=== NAME TestClientCADerivations1523 client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1524 warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1525 error: binary cache 's3://bucket55?endpoint=http://localhost:45911®ion=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations2028460159/001/store'15262026/10/06 08:15:56 INFO Received cleanup request method=DELETE path=/api/pending_closures1527 client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 115282026/10/06 08:15:56 INFO Aborted multipart uploads count=1 kept=015292026/10/06 08:15:56 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete15302026-10-06 08:15:56.605 UTC [1603] ERROR: Closure does not exist: id=115312026-10-06 08:15:56.605 UTC [1603] CONTEXT: PL/pgSQL function commit_pending_closure(bigint) line 12 at RAISE15322026-10-06 08:15:56.605 UTC [1603] STATEMENT: -- name: CommitPendingClosure :exec1533 SELECT commit_pending_closure($1::bigint)1534 1535--- PASS: TestService_cleanupPendingClosuresHandler (0.44s)1536=== CONT TestPush_SkippedKeySurvivesGCBeforeCommit1537--- PASS: TestClientCADerivations (0.84s)1538=== CONT TestPush_CompleteCommitsEveryRoot15392026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures15402026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures15412026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures1542=== RUN TestService_AuthMiddleware_OIDC/valid_OIDC_token1543=== PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1544=== RUN TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1545=== PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1546=== RUN TestService_AuthMiddleware_OIDC/malformed_token_rejected1547=== PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1548=== RUN TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1549=== PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1550=== CONT TestReadProxyRangeRequest15512026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures15522026/10/06 08:15:56 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1553--- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.59s)15542026/10/06 08:15:56 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst1555=== CONT TestReadRedirectUsesPublicS3URL1556--- PASS: TestCompleteMultipartUnregistered (0.58s)1557=== CONT TestReadRedirectKeepsNarinfoProxied1558--- PASS: TestService_Rustfstest (0.33s)1559=== CONT TestForceGCDuringPushOffersSweptObject/before_pending_rows1560=== RUN TestPush_RejectsBadRequests/no_roots1561=== PAUSE TestPush_RejectsBadRequests/no_roots1562=== RUN TestPush_RejectsBadRequests/no_objects1563=== PAUSE TestPush_RejectsBadRequests/no_objects1564=== RUN TestPush_RejectsBadRequests/bad_root1565=== PAUSE TestPush_RejectsBadRequests/bad_root1566=== RUN TestPush_RejectsBadRequests/root_not_in_objects1567=== PAUSE TestPush_RejectsBadRequests/root_not_in_objects1568=== CONT TestForceGCDuringPushOffersSweptObject/after_presence_check15692026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures15702026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures15712026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures15722026/10/06 08:15:56 INFO Received complete multipart upload request method=POST path=/api/multipart/complete15732026/10/06 08:15:56 WARN CompleteMultipartUpload errored but object exists; treating as success error="The specified key does not exist." object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=Zjg3ZDI3NDktYjUxNi00MGZjLWJiZGMtZWIyNThkNTg0Zjc4LmY3M2FmYTU5LTU4NzAtNDhhZS05MjMyLTAxZGJmOGI0NjE4ZHgxNzkxMjc0NTU2Nzk3MTI2ODU015742026/10/06 08:15:56 INFO Registered completed upload object_key=nar/jjjjjjjjjjjjjjjjjjjjjjjjjjjjjj0100000000000000000000.nar.zst15752026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures15762026/10/06 08:15:56 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=Zjg3ZDI3NDktYjUxNi00MGZjLWJiZGMtZWIyNThkNTg0Zjc4LmY3M2FmYTU5LTU4NzAtNDhhZS05MjMyLTAxZGJmOGI0NjE4ZHgxNzkxMjc0NTU2Nzk3MTI2ODU0 parts=11577--- PASS: TestPresignedUploadRegisteredBeforeCommit (0.45s)1578=== CONT TestIsValidCachePath/narinfo1579=== CONT TestIsValidCachePath/index.html1580=== CONT TestIsValidCachePath/short_hash1581=== CONT TestIsValidCachePath/wrong_extension1582=== CONT TestIsValidCachePath/leading_slash1583=== CONT TestIsValidCachePath/empty1584=== CONT TestIsValidCachePath/random_path1585=== CONT TestIsValidCachePath/invalid_char_u1586=== CONT TestIsValidCachePath/invalid_char_e1587=== CONT TestIsValidCachePath/traversal_in_middle15882026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures1589=== CONT TestIsValidCachePath/traversal_parent1590=== CONT TestIsValidCachePath/nar_xz1591=== CONT TestIsValidCachePath/nar_bz21592=== CONT TestIsValidCachePath/realisation1593=== CONT TestIsValidCachePath/nix-cache-info1594=== CONT TestIsValidCachePath/log1595=== CONT TestIsValidCachePath/ls1596=== CONT TestIsValidCachePath/nar_zst1597=== CONT TestIsValidCachePath/narinfo_all_nix_base32_chars1598=== CONT TestParseSingleRange/none1599=== CONT TestParseSingleRange/open-ended1600=== CONT TestParseSingleRange/start_far_past_EOF1601=== CONT TestParseSingleRange/start_past_EOF1602=== CONT TestParseSingleRange/single_byte1603=== CONT TestParseSingleRange/end_clamped_to_size1604=== CONT TestParseSingleRange/suffix_exceeds_size1605=== CONT TestParseSingleRange/malformed_both_empty1606=== CONT TestParseSingleRange/closed1607=== CONT TestParseSingleRange/suffix1608=== CONT TestParseSingleRange/malformed_end_before_start1609=== CONT TestParseSingleRange/multi-range_ignored1610=== CONT TestParseSingleRange/malformed_no_dash1611=== CONT TestParseSingleRange/unknown_unit1612=== CONT TestResolveDBConnectionString/flag_wins1613=== CONT TestResolveDBConnectionString/PGHOST_allows_empty1614--- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.44s)1615=== CONT TestIsValidCachePath/nar_uncompressed1616--- PASS: TestIsValidCachePath (0.00s)1617 --- PASS: TestIsValidCachePath/narinfo (0.00s)1618 --- PASS: TestIsValidCachePath/index.html (0.00s)1619 --- PASS: TestIsValidCachePath/short_hash (0.00s)1620 --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1621 --- PASS: TestIsValidCachePath/leading_slash (0.00s)1622 --- PASS: TestIsValidCachePath/empty (0.00s)1623 --- PASS: TestIsValidCachePath/random_path (0.00s)1624 --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1625 --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1626 --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1627 --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1628 --- PASS: TestIsValidCachePath/nar_xz (0.00s)1629 --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1630 --- PASS: TestIsValidCachePath/realisation (0.00s)1631 --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1632 --- PASS: TestIsValidCachePath/log (0.00s)1633 --- PASS: TestIsValidCachePath/ls (0.00s)1634 --- PASS: TestIsValidCachePath/nar_zst (0.00s)1635 --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)1636 --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1637=== CONT TestResolveDBConnectionString/nothing_configured1638=== CONT TestResolveDBConnectionString/file_when_flag_empty1639=== CONT TestResolveDBConnectionString/missing_file_is_an_error1640--- PASS: TestParseSingleRange (0.00s)1641 --- PASS: TestParseSingleRange/none (0.00s)1642 --- PASS: TestParseSingleRange/open-ended (0.00s)1643 --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1644 --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1645 --- PASS: TestParseSingleRange/single_byte (0.00s)1646 --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1647 --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1648 --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1649 --- PASS: TestParseSingleRange/closed (0.00s)1650 --- PASS: TestParseSingleRange/suffix (0.00s)1651 --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1652 --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1653 --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1654 --- PASS: TestParseSingleRange/unknown_unit (0.00s)1655=== CONT TestServerTLSConfig/no_client_CA1656=== CONT TestServerTLSConfig/missing_CA_file1657=== CONT TestServerTLSConfig/not_a_PEM_file1658=== CONT TestClientErrorHandling/InvalidStorePath1659--- PASS: TestResolveDBConnectionString (0.00s)1660 --- PASS: TestResolveDBConnectionString/flag_wins (0.00s)1661 --- PASS: TestResolveDBConnectionString/PGHOST_allows_empty (0.00s)1662 --- PASS: TestResolveDBConnectionString/nothing_configured (0.00s)1663 --- PASS: TestResolveDBConnectionString/file_when_flag_empty (0.00s)1664 --- PASS: TestResolveDBConnectionString/missing_file_is_an_error (0.00s)1665=== CONT TestClientErrorHandling/ServerNotAvailable1666--- PASS: TestServerTLSConfig (0.00s)1667 --- PASS: TestServerTLSConfig/no_client_CA (0.00s)1668 --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)1669 --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)16702026/10/06 08:15:56 INFO Received complete multipart upload request method=POST path=/api/multipart/complete16712026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures16722026/10/06 08:15:56 INFO Received uploads request method=POST path=/api/pending_closures16732026/10/06 08:15:56 INFO lead: released remote=192.0.2.1:123416742026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes16752026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign16762026/10/06 08:15:56 INFO Signed narinfos id=1 count=116772026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign16782026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign16792026/10/06 08:15:56 ERROR Failed to sign narinfos signer="index 1" error="signing key cannot be nil"16802026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign16812026/10/06 08:15:56 ERROR Failed to sign narinfos signer=niks3-test-1 error="signer failed"16822026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign16832026/10/06 08:15:56 ERROR Signer returned unexpected signature count signer=niks3-test-1 got=0 want=116842026/10/06 08:15:56 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign16852026/10/06 08:15:56 ERROR Signer returned unexpected object key signer=niks3-test-1 object_key=unexpected.narinfo1686--- PASS: TestPush_SignsNarinfosOfItsPendingObjects (0.35s)1687=== CONT TestClientErrorHandling/InvalidAuthToken16882026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes16892026/10/06 08:15:56 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/present1690--- PASS: TestPush_OverlappingRootsStoreOneRowPerKey (0.37s)1691=== CONT TestCacheConfigHandler/full_config,_no_issuer1692=== CONT TestCacheConfigHandler/no_signing_keys1693=== CONT TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator16942026/10/06 08:15:56 INFO lead: acquired remote=192.0.2.1:12341695=== CONT TestCacheConfigHandler/no_cache_url_configured1696--- PASS: TestCacheConfigHandler (0.00s)1697 --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)1698 --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)1699 --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)1700 --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)1701=== CONT TestGCEndsOnShutdown/before_the_run17022026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes17032026/10/06 08:15:56 INFO Received complete push request method=POST path=/api/pushes/1/complete1704--- PASS: TestPush_CompleteCommitsEveryRoot (0.35s)1705=== CONT TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark17062026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes17072026/10/06 08:15:56 INFO Received complete multipart upload request method=POST path=/api/multipart/complete17082026/10/06 08:15:56 INFO Received complete push request method=POST path=/api/pushes/1/complete1709--- PASS: TestPushWaitsForSweepHoldingRow (0.90s)1710=== CONT TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete17112026/10/06 08:15:56 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=Zjg3ZDI3NDktYjUxNi00MGZjLWJiZGMtZWIyNThkNTg0Zjc4Ljk5NzliMmY2LTJjZWItNGM3Yy1hZDc4LTJmZWQ2YWU0OGU5M3gxNzkxMjc0NTU2NTY4NTE2Mjc0 parts=1017122026/10/06 08:15:56 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete17132026/10/06 08:15:56 INFO Received push request method=POST path=/api/pushes17142026/10/06 08:15:57 INFO Completed upload id=117152026/10/06 08:15:57 INFO Received uploads request method=POST path=/api/pending_closures1716--- PASS: TestReadProxyRangeRequest (0.37s)1717=== CONT TestService_RequireScope_OIDC/builder_may_write17182026/10/06 08:15:57 INFO Received uploads request method=POST path=/api/pending_closures1719=== CONT TestService_RequireScope_OIDC/reader_may_read1720=== CONT TestService_RequireScope_OIDC/static_token_may_write1721=== CONT TestService_RequireScope_OIDC/static_token_may_admin1722=== CONT TestService_RequireScope_OIDC/reader_may_not_write1723=== CONT TestService_RequireScope_OIDC/ops_may_not_write1724=== CONT TestService_RequireScope_OIDC/ops_may_admin1725=== CONT TestService_RequireScope_OIDC/builder_may_not_admin1726=== CONT TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read17272026/10/06 08:15:57 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]1728=== CONT TestService_RequireScope_OIDC/writer_implies_read1729=== CONT TestService_RequireScope_OIDC/static_token_may_admin_with_netrc1730=== CONT TestService_RequireScope_OIDC/reader_may_read_with_netrc1731=== CONT TestService_RequireScope_OIDC/anonymous_may_not_read1732=== CONT TestService_RequireScope_OIDC/empty_netrc_password_may_not_read1733=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info17342026/10/06 08:15:57 INFO Received uploads request method=POST path=/1735=== CONT TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key17362026/10/06 08:15:57 INFO Received complete multipart upload request method=POST path=/1737=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal17382026/10/06 08:15:57 INFO Received uploads request method=POST path=/1739--- PASS: TestService_RequireScope_OIDC (0.26s)1740 --- PASS: TestService_RequireScope_OIDC/builder_may_write (0.00s)1741 --- PASS: TestService_RequireScope_OIDC/reader_may_read (0.00s)1742 --- PASS: TestService_RequireScope_OIDC/static_token_may_write (0.00s)1743 --- PASS: TestService_RequireScope_OIDC/static_token_may_admin (0.00s)1744 --- PASS: TestService_RequireScope_OIDC/reader_may_not_write (0.00s)1745 --- PASS: TestService_RequireScope_OIDC/ops_may_not_write (0.00s)1746 --- PASS: TestService_RequireScope_OIDC/ops_may_admin (0.00s)1747 --- PASS: TestService_RequireScope_OIDC/builder_may_not_admin (0.00s)1748 --- PASS: TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read (0.00s)1749 --- PASS: TestService_RequireScope_OIDC/writer_implies_read (0.00s)1750 --- PASS: TestService_RequireScope_OIDC/static_token_may_admin_with_netrc (0.00s)1751 --- PASS: TestService_RequireScope_OIDC/reader_may_read_with_netrc (0.00s)1752 --- PASS: TestService_RequireScope_OIDC/anonymous_may_not_read (0.00s)1753 --- PASS: TestService_RequireScope_OIDC/empty_netrc_password_may_not_read (0.00s)1754=== CONT TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key17552026/10/06 08:15:57 INFO Received request for more parts method=POST path=/1756--- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)1757 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1758 --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1759 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1760 --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1761=== CONT TestIsValidUploadKey/narinfo1762=== CONT TestIsValidUploadKey/realisation_plus_in_output1763=== CONT TestIsValidUploadKey/realisation1764=== CONT TestIsValidUploadKey/build_log_equals1765=== CONT TestIsValidUploadKey/build_log_question_mark1766=== CONT TestIsValidUploadKey/nix-cache-info1767=== CONT TestIsValidUploadKey/build_log_plus_in_name1768=== CONT TestIsValidUploadKey/build_log_home-manager_file1769=== CONT TestIsValidUploadKey/build_log1770=== CONT TestIsValidUploadKey/listing1771=== CONT TestIsValidUploadKey/nar_plain1772=== CONT TestIsValidUploadKey/nar_xz1773=== CONT TestIsValidUploadKey/nar_zst1774=== CONT TestIsValidUploadKey/traversal1775=== CONT TestIsValidUploadKey/unknown_type1776=== CONT TestIsValidUploadKey/empty_key1777=== CONT TestIsValidUploadKey/absolute1778=== CONT TestIsValidUploadKey/traversal_nar1779=== CONT TestIsValidUploadKey/narinfo_key,_nar_type1780=== CONT TestIsValidUploadKey/listing_key,_narinfo_type1781=== CONT TestIsValidUploadKey/index.html1782=== CONT TestIsValidUploadKey/nar_key,_narinfo_type1783--- PASS: TestIsValidUploadKey (0.00s)1784 --- PASS: TestIsValidUploadKey/narinfo (0.00s)1785 --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1786 --- PASS: TestIsValidUploadKey/realisation (0.00s)1787 --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1788 --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1789 --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1790 --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1791 --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1792 --- PASS: TestIsValidUploadKey/build_log (0.00s)1793 --- PASS: TestIsValidUploadKey/listing (0.00s)1794 --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1795 --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1796 --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1797 --- PASS: TestIsValidUploadKey/traversal (0.00s)1798 --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1799 --- PASS: TestIsValidUploadKey/empty_key (0.00s)1800 --- PASS: TestIsValidUploadKey/absolute (0.00s)1801 --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1802 --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1803 --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1804 --- PASS: TestIsValidUploadKey/index.html (0.00s)1805 --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1806=== CONT TestPendingClosureWriteTimeout/empty1807=== CONT TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline18082026/10/06 08:15:57 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo18092026/10/06 08:15:57 WARN Found objects in DB but missing from S3, will re-upload count=11810--- PASS: TestService_verifyS3Integrity (0.90s)1811=== CONT TestPendingClosureWriteTimeout/670k_objects1812=== CONT TestPendingClosureWriteTimeout/400_objects1813=== CONT TestProxyWriteTimeout/narinfo1814=== CONT TestProxyWriteTimeout/unknown_size1815=== CONT TestProxyWriteTimeout/10_GiB_nar1816=== CONT TestProxyWriteTimeout/1_GiB_nar1817--- PASS: TestProxyWriteTimeout (0.00s)1818 --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1819 --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1820 --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1821 --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)1822=== CONT TestUploadHandlersRejectOversizedBody/complete_multipart18232026/10/06 08:15:57 INFO Received complete multipart upload request method=POST path=/18242026/10/06 08:15:57 INFO Received complete multipart upload request method=POST path=/api/multipart/complete18252026/10/06 08:15:57 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=188.6647ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present18262026/10/06 08:15:57 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=Zjg3ZDI3NDktYjUxNi00MGZjLWJiZGMtZWIyNThkNTg0Zjc4LjQ4YjZjY2MyLWM5YWItNDc1NS04NjUyLTExOTczMWE5NzJhNXgxNzkxMjc0NTU2NjIzMDQwMzc5 parts=1018272026/10/06 08:15:57 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1828--- PASS: TestReadRedirectUsesPublicS3URL (0.36s)1829=== CONT TestUploadHandlersRejectOversizedBody/create_pending_closure18302026/10/06 08:15:57 INFO Received uploads request method=POST path=/1831=== CONT TestUploadHandlersRejectOversizedBody/request_more_parts18322026/10/06 08:15:57 INFO Received request for more parts method=POST path=/18332026/10/06 08:15:57 INFO Completed upload id=118342026/10/06 08:15:57 INFO Received get closure request method=GET path=/api/closures/0000000000000000000000000000000018352026/10/06 08:15:57 INFO Received uploads request method=POST path=/api/pending_closures18362026/10/06 08:15:57 INFO Starting cleanup of old closures method=DELETE path=/api/closures1837--- PASS: TestReadRedirectKeepsNarinfoProxied (0.37s)1838=== CONT TestService_AuthMiddleware_OIDC/valid_OIDC_token18392026/10/06 08:15:57 INFO Aborted multipart uploads count=0 kept=01840=== CONT TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1841=== CONT TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected18422026/10/06 08:15:57 WARN Force mode enabled - objects will be deleted immediately without grace period18432026/10/06 08:15:57 WARN Authentication failed token_preview=eyJhbGciOi...Rk-W87mE5A token_length=701 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]1844=== CONT TestService_AuthMiddleware_OIDC/malformed_token_rejected18452026/10/06 08:15:57 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]1846=== CONT TestPush_RejectsBadRequests/no_roots18472026/10/06 08:15:57 INFO Received push request method=POST path=/api/pushes1848=== CONT TestPush_RejectsBadRequests/bad_root18492026/10/06 08:15:57 INFO Received push request method=POST path=/api/pushes1850--- PASS: TestService_AuthMiddleware_OIDC (0.72s)1851 --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1852 --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1853 --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)1854 --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)18552026/10/06 08:15:57 INFO Aborted multipart uploads count=0 kept=01856=== CONT TestPush_RejectsBadRequests/no_objects18572026/10/06 08:15:57 INFO Received push request method=POST path=/api/pushes1858=== CONT TestPush_RejectsBadRequests/root_not_in_objects18592026/10/06 08:15:57 INFO Received push request method=POST path=/api/pushes1860--- PASS: TestPush_RejectsBadRequests (0.37s)1861 --- PASS: TestPush_RejectsBadRequests/no_roots (0.00s)1862 --- PASS: TestPush_RejectsBadRequests/bad_root (0.00s)1863 --- PASS: TestPush_RejectsBadRequests/no_objects (0.00s)1864 --- PASS: TestPush_RejectsBadRequests/root_not_in_objects (0.00s)18652026/10/06 08:15:57 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=018662026/10/06 08:15:57 INFO Vacuumed table table=pending_closures18672026/10/06 08:15:57 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=018682026/10/06 08:15:57 INFO Vacuumed table table=pending_objects18692026/10/06 08:15:57 INFO Vacuumed table table=multipart_uploads18702026/10/06 08:15:57 INFO Vacuumed table table=pending_closures18712026/10/06 08:15:57 INFO Received uploads request method=POST path=/api/pending_closures18722026/10/06 08:15:57 INFO Vacuumed table table=closures18732026/10/06 08:15:57 INFO Vacuumed table table=pending_objects18742026/10/06 08:15:57 INFO Vacuumed table table=objects18752026/10/06 08:15:57 INFO Vacuumed table table=multipart_uploads18762026/10/06 08:15:57 INFO Aborted multipart uploads count=0 kept=018772026/10/06 08:15:57 INFO Vacuumed table table=closures18782026/10/06 08:15:57 WARN Force mode enabled - objects will be deleted immediately without grace period18792026/10/06 08:15:57 INFO Vacuumed table table=objects18802026/10/06 08:15:57 INFO Received complete push request method=POST path=/api/pushes/2/complete1881--- PASS: TestPush_SkippedKeySurvivesGCBeforeCommit (0.50s)18822026/10/06 08:15:57 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000001883--- PASS: TestService_createPendingClosureHandler (0.97s)18842026/10/06 08:15:57 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=018852026/10/06 08:15:57 INFO Vacuumed table table=pending_closures18862026/10/06 08:15:57 INFO Vacuumed table table=pending_objects18872026/10/06 08:15:57 INFO Vacuumed table table=multipart_uploads18882026/10/06 08:15:57 INFO Vacuumed table table=closures18892026/10/06 08:15:57 INFO Received uploads request method=POST path=/api/pending_closures18902026/10/06 08:15:57 INFO Vacuumed table table=objects18912026/10/06 08:15:57 INFO Aborted multipart uploads count=0 kept=018922026/10/06 08:15:57 WARN Force mode enabled - objects will be deleted immediately without grace period18932026/10/06 08:15:57 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=018942026/10/06 08:15:57 INFO Vacuumed table table=pending_closures18952026/10/06 08:15:57 INFO Vacuumed table table=pending_objects18962026/10/06 08:15:57 INFO Vacuumed table table=multipart_uploads18972026/10/06 08:15:57 INFO Vacuumed table table=closures18982026/10/06 08:15:57 INFO Vacuumed table table=objects1899--- PASS: TestForceGCDuringPushOffersSweptObject (0.01s)1900 --- PASS: TestForceGCDuringPushOffersSweptObject/before_pending_rows (0.42s)1901 --- PASS: TestForceGCDuringPushOffersSweptObject/after_presence_check (0.41s)19022026/10/06 08:15:57 INFO Aborted multipart uploads count=0 kept=019032026/10/06 08:15:57 WARN Force mode enabled - objects will be deleted immediately without grace period19042026/10/06 08:15:57 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=373.515498ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19052026/10/06 08:15:57 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"19062026/10/06 08:15:57 INFO Aborted multipart uploads count=0 kept=019072026/10/06 08:15:57 WARN Force mode enabled - objects will be deleted immediately without grace period19082026/10/06 08:15:57 ERROR failed to remove object object=nar/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.nar.zst error="Post \"http://localhost:45911/bucket90/?delete=\": context canceled"1909--- PASS: TestGCEndsOnShutdown (0.00s)1910 --- PASS: TestGCEndsOnShutdown/before_the_run (0.24s)1911 --- PASS: TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark (0.26s)1912 --- PASS: TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete (0.25s)19132026/10/06 08:15:57 INFO Received uploads request method=POST path=/api/pending_closures19142026/10/06 08:15:57 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"19152026/10/06 08:15:57 INFO Received complete multipart upload request method=POST path=/api/multipart/complete19162026/10/06 08:15:57 INFO Completed multipart upload object_key=nar/hhhhhhhhhhhhhhhhhhhhhhhhhhhhhh0100000000000000000000.nar.zst upload_id=Zjg3ZDI3NDktYjUxNi00MGZjLWJiZGMtZWIyNThkNTg0Zjc4LmM3ODExODQ4LWNhMjUtNDdhMi04YTU0LTk1NmUzOGUwZmRlMXgxNzkxMjc0NTU2ODQ1OTM1Mzcw parts=1219172026/10/06 08:15:57 INFO Received uploads request method=POST path=/api/pending_closures1918--- PASS: TestCompletedNarNotReofferedAcrossClosures (0.94s)19192026/10/06 08:15:57 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1920=== NAME TestOrphanedObjectsGCStressTest1921 orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains19222026/10/06 08:15:57 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=Zjg3ZDI3NDktYjUxNi00MGZjLWJiZGMtZWIyNThkNTg0Zjc4LjIxMWExZTIyLTg0YmUtNDJiYy04YjRlLTEyOWE5Y2FhYTg0ZXgxNzkxMjc0NTU2ODcwNTY0NzYz parts=1219232026/10/06 08:15:57 INFO Received uploads request method=POST path=/api/pending_closures19242026/10/06 08:15:57 INFO Received uploads request method=POST path=/api/pending_closures1925 orphaned_objects_gc_test.go:446: Marked 210 objects for deletion19262026/10/06 08:15:57 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=818.045632ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present1927--- PASS: TestUploadHandlersRejectOversizedBody (0.19s)1928 --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.03s)1929 --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.03s)1930 --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.60s)1931=== NAME TestOrphanedObjectsGCStressTest1932 orphaned_objects_gc_test.go:509: Stress test completed successfully:1933 orphaned_objects_gc_test.go:510: - Active objects preserved: 201934 orphaned_objects_gc_test.go:511: - Objects deleted: 2101935 orphaned_objects_gc_test.go:512: - Total GC'd: 2101936--- PASS: TestOrphanedObjectsGCStressTest (2.69s)19372026/10/06 08:15:57 INFO Received complete multipart upload request method=POST path=/api/multipart/complete19382026/10/06 08:15:57 WARN Failed to abort redundant multipart upload, keeping its row object_key=nar/gggggggggggggggggggggggggggggg0200000000000000000000.nar.zst upload_id=Zjg3ZDI3NDktYjUxNi00MGZjLWJiZGMtZWIyNThkNTg0Zjc4LmRkZjE5OGVhLTNlYmEtNDA3Ny04ZjMxLTM0ZTAzYjU1ZDA5N3gxNzkxMjc0NTU3Mzc4MzM2NDc0 error="Get \"http://127.0.0.1:1/bucket76/?location=\": dial tcp 127.0.0.1:1: connect: connection refused"19392026/10/06 08:15:57 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0200000000000000000000.nar.zst upload_id=Zjg3ZDI3NDktYjUxNi00MGZjLWJiZGMtZWIyNThkNTg0Zjc4LjY0NmUyNTViLTM3NzktNDRiNC05ODY2LTNmYTMwZjc4Zjg4MHgxNzkxMjc0NTU3MzcxNTU2NzU3 parts=1219402026/10/06 08:15:57 INFO Received cleanup request method=DELETE path=/api/pending_closures19412026/10/06 08:15:57 INFO Aborted multipart uploads count=1 kept=01942--- PASS: TestRedundantMultipartUpload (1.49s)19432026/10/06 08:15:57 INFO lead: released remote=192.0.2.1:12341944--- PASS: TestLeadElectsOneAndHandsOver (2.83s)1945--- PASS: TestPendingClosureWriteTimeout (0.10s)1946 --- PASS: TestPendingClosureWriteTimeout/empty (0.00s)1947 --- PASS: TestPendingClosureWriteTimeout/670k_objects (0.00s)1948 --- PASS: TestPendingClosureWriteTimeout/400_objects (0.00s)1949 --- PASS: TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline (1.03s)19502026/10/06 08:15:58 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.681724571s error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19512026/10/06 08:15:58 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=3 objects_failed=019522026/10/06 08:15:58 INFO Received create pin request method=POST path=/api/pins/myapp19532026/10/06 08:15:58 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC798678934/001/store/8zd2m4z51340nkd325f4kjy10vcbn97b-pinned-file.txt narinfo_key=8zd2m4z51340nkd325f4kjy10vcbn97b.narinfo19542026/10/06 08:15:58 INFO Starting cleanup of old closures method=DELETE path=/api/closures19552026/10/06 08:15:58 INFO Garbage collection started19562026/10/06 08:15:58 INFO Aborted multipart uploads count=0 kept=019572026/10/06 08:15:58 WARN Force mode enabled - objects will be deleted immediately without grace period19582026/10/06 08:15:58 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=0 objects-deleted-after-grace-period=0 objects-failed-to-delete=019592026/10/06 08:15:58 INFO Vacuumed table table=pending_closures19602026/10/06 08:15:58 INFO Vacuumed table table=pending_objects19612026/10/06 08:15:58 INFO Vacuumed table table=multipart_uploads19622026/10/06 08:15:58 INFO Vacuumed table table=closures19632026/10/06 08:15:58 INFO Vacuumed table table=objects19642026/10/06 08:15:58 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=3 objects_failed=01965=== NAME TestClientIntegration1966 client_integration_test.go:323: Objects in database after GC:1967 client_integration_test.go:323: Successfully deleted all objects with GC --force1968--- PASS: TestClientIntegration (2.76s)1969--- PASS: TestReadProxyOutlastsServerWriteTimeout (4.97s)19702026/10/06 08:16:00 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-config19712026/10/06 08:16:00 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=200.505582ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config1972--- PASS: TestConnectWaitsForAPeerMigration (5.23s)1973=== NAME TestPinProtectsFromGC1974 client_integration_test.go:840: Pin successfully protected closure from garbage collection19752026/10/06 08:16:00 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=383.896412ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config1976--- PASS: TestPinProtectsFromGC (4.97s)19772026/10/06 08:16:00 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=774.427225ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19782026/10/06 08:16:01 WARN Rate limiter enabled after throttle name=s3-test rate=519792026/10/06 08:16:01 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1980=== NAME TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1981 throttle_test.go:213: Proxy stats: total=13, throttled=10, completeMultipart=101982 throttle_test.go:215: Rate limiter: enabled=true, rate=5.001983--- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (4.78s)19842026/10/06 08:16:01 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.590694567s error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19852026/10/06 08:16:01 INFO Aborted multipart uploads count=1 kept=019862026/10/06 08:16:01 INFO Received cleanup request method=DELETE path=/api/pending_closures19872026/10/06 08:16:01 INFO Aborted multipart uploads count=1 kept=01988--- PASS: TestPendingCleanupUsesOneCutoff (6.53s)19892026/10/06 08:16:03 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"19902026/10/06 08:16:03 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-config19912026/10/06 08:16:03 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=187.725047ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19922026/10/06 08:16:03 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=360.136757ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19932026/10/06 08:16:03 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=854.80913ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19942026/10/06 08:16:04 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.493370643s error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config19952026/10/06 08:16:06 WARN Request failed, retrying attempt=1 max_attempts=6 backoff=100ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures19962026/10/06 08:16:06 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=208.59043ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures19972026/10/06 08:16:06 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=411.803325ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures19982026/10/06 08:16:06 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=793.404122ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures19992026/10/06 08:16:07 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.507661927s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures2000--- PASS: TestClientErrorHandling (0.00s)2001 --- PASS: TestClientErrorHandling/InvalidStorePath (0.32s)2002 --- PASS: TestClientErrorHandling/InvalidAuthToken (0.36s)2003 --- PASS: TestClientErrorHandling/ServerNotAvailable (12.39s)2004PASS2005{"timestamp":"2026-10-06T08:16:09.232681392Z","level":"ERROR","message":"HTTP transport failed","event":"http_transport_failed","component":"server","subsystem":"transport","peer_addr":"127.0.0.1:40104","error_kind":"io_error","error":"Cancelled","result":"transport_error","target":"rustfs::server::http","filename":"rustfs/src/server/http.rs","line_number":2354,"threadName":"rustfs-worker","threadId":"ThreadId(401)"}20062026-10-06 08:16:09.609 UTC [129] LOG: received smart shutdown request20072026-10-06 08:16:09.614 UTC [129] LOG: background worker "logical replication launcher" (PID 139) exited with exit code 120082026-10-06 08:16:09.621 UTC [134] LOG: shutting down20092026-10-06 08:16:09.622 UTC [134] LOG: checkpoint starting: shutdown immediate20102026-10-06 08:16:11.751 UTC [134] LOG: checkpoint complete: wrote 11348 buffers (69.3%), wrote 5 SLRU buffers; 0 WAL file(s) added, 0 removed, 26 recycled; write=0.291 s, sync=1.782 s, total=2.130 s; sync files=31000, longest=0.002 s, average=0.001 s; distance=428555 kB, estimate=428555 kB; lsn=0/1B9E8B20, redo lsn=0/1B9E8B2020112026-10-06 08:16:11.815 UTC [129] LOG: database system is shut down2012Running OIDC tests...2013=== RUN TestAudienceForIssuer2014=== PAUSE TestAudienceForIssuer2015=== RUN TestGlobMatch2016=== PAUSE TestGlobMatch2017=== RUN TestValidateToken_ValidToken2018=== PAUSE TestValidateToken_ValidToken2019=== RUN TestValidateToken_WrongAudience2020=== PAUSE TestValidateToken_WrongAudience2021=== RUN TestValidateToken_Expired2022=== PAUSE TestValidateToken_Expired2023=== RUN TestValidateToken_BoundClaimsMismatch2024=== PAUSE TestValidateToken_BoundClaimsMismatch2025=== RUN TestValidateToken_BoundSubjectMismatch2026=== PAUSE TestValidateToken_BoundSubjectMismatch2027=== RUN TestValidateToken_MultipleProviders2028=== PAUSE TestValidateToken_MultipleProviders2029=== RUN TestValidateToken_NoMatchingProvider2030=== PAUSE TestValidateToken_NoMatchingProvider2031=== RUN TestValidateToken_KubernetesServiceAccount2032=== PAUSE TestValidateToken_KubernetesServiceAccount2033=== RUN TestNewValidator_KubernetesRequiresCA2034=== PAUSE TestNewValidator_KubernetesRequiresCA2035=== RUN TestValidateToken_KubernetesIssuerFromOwnToken2036=== PAUSE TestValidateToken_KubernetesIssuerFromOwnToken2037=== RUN TestPins_ReservedForMatchingRule2038=== PAUSE TestPins_ReservedForMatchingRule2039=== RUN TestPins_TopLevelShorthand2040=== PAUSE TestPins_TopLevelShorthand2041=== RUN TestPins_ConfigValidation2042=== PAUSE TestPins_ConfigValidation2043=== RUN TestScopes_LegacyProviderDefaultsToWrite2044=== PAUSE TestScopes_LegacyProviderDefaultsToWrite2045=== RUN TestScopes_Rules2046=== PAUSE TestScopes_Rules2047=== RUN TestScopes_ConfigValidation2048=== PAUSE TestScopes_ConfigValidation2049=== CONT TestAudienceForIssuer2050=== CONT TestValidateToken_KubernetesServiceAccount2051=== CONT TestValidateToken_BoundClaimsMismatch2052--- PASS: TestAudienceForIssuer (0.00s)2053=== CONT TestValidateToken_NoMatchingProvider2054=== CONT TestValidateToken_ValidToken2055=== CONT TestValidateToken_MultipleProviders2056=== CONT TestGlobMatch2057=== RUN TestGlobMatch/foo_foo2058=== CONT TestValidateToken_BoundSubjectMismatch2059=== CONT TestPins_ConfigValidation2060=== CONT TestPins_ReservedForMatchingRule2061=== CONT TestScopes_ConfigValidation2062=== CONT TestScopes_Rules2063=== CONT TestPins_TopLevelShorthand2064=== CONT TestScopes_LegacyProviderDefaultsToWrite2065=== CONT TestValidateToken_KubernetesIssuerFromOwnToken2066=== CONT TestValidateToken_Expired2067=== CONT TestNewValidator_KubernetesRequiresCA2068=== CONT TestValidateToken_WrongAudience2069=== PAUSE TestGlobMatch/foo_foo2070--- PASS: TestPins_ConfigValidation (0.00s)2071=== RUN TestGlobMatch/foo_bar2072=== PAUSE TestGlobMatch/foo_bar2073=== RUN TestGlobMatch/*_2074=== PAUSE TestGlobMatch/*_2075=== RUN TestGlobMatch/*_anything2076=== PAUSE TestGlobMatch/*_anything2077=== RUN TestGlobMatch/foo*_foo2078--- PASS: TestScopes_ConfigValidation (0.00s)2079=== PAUSE TestGlobMatch/foo*_foo2080=== RUN TestGlobMatch/foo*_foobar2081=== PAUSE TestGlobMatch/foo*_foobar2082=== RUN TestGlobMatch/foo*_bar2083=== PAUSE TestGlobMatch/foo*_bar2084=== RUN TestGlobMatch/*bar_bar2085=== PAUSE TestGlobMatch/*bar_bar2086=== RUN TestGlobMatch/*bar_foobar2087=== PAUSE TestGlobMatch/*bar_foobar2088=== RUN TestGlobMatch/*bar_foo2089=== PAUSE TestGlobMatch/*bar_foo2090=== RUN TestGlobMatch/foo*bar_foobar2091=== PAUSE TestGlobMatch/foo*bar_foobar2092=== RUN TestGlobMatch/foo*bar_foo123bar2093=== PAUSE TestGlobMatch/foo*bar_foo123bar2094=== RUN TestGlobMatch/foo*bar_foobarbaz2095=== PAUSE TestGlobMatch/foo*bar_foobarbaz2096=== RUN TestGlobMatch/*/*_foo/bar2097=== PAUSE TestGlobMatch/*/*_foo/bar2098=== RUN TestGlobMatch/*/*_foo2099=== PAUSE TestGlobMatch/*/*_foo2100=== RUN TestGlobMatch/refs/heads/*_refs/heads/main2101=== PAUSE TestGlobMatch/refs/heads/*_refs/heads/main2102=== RUN TestGlobMatch/refs/heads/*_refs/tags/v1.02103=== PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.02104=== RUN TestGlobMatch/refs/*/main_refs/heads/main2105=== PAUSE TestGlobMatch/refs/*/main_refs/heads/main2106=== RUN TestGlobMatch/fo?_foo2107=== PAUSE TestGlobMatch/fo?_foo2108=== RUN TestGlobMatch/fo?_fo2109=== PAUSE TestGlobMatch/fo?_fo2110=== RUN TestGlobMatch/fo?_fooo2111=== PAUSE TestGlobMatch/fo?_fooo2112=== RUN TestGlobMatch/?oo_foo2113=== PAUSE TestGlobMatch/?oo_foo2114=== RUN TestGlobMatch/?oo_boo2115=== PAUSE TestGlobMatch/?oo_boo2116=== RUN TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2117=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2118=== RUN TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2119=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2120=== CONT TestGlobMatch/foo_foo2121=== CONT TestGlobMatch/refs/*/main_refs/heads/main2122=== CONT TestGlobMatch/*bar_bar2123=== CONT TestGlobMatch/*_2124=== CONT TestGlobMatch/fo?_fo2125=== CONT TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2126=== CONT TestGlobMatch/fo?_foo2127=== CONT TestGlobMatch/foo*bar_foo123bar2128=== CONT TestGlobMatch/foo_bar2129=== CONT TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2130=== CONT TestGlobMatch/?oo_boo2131=== CONT TestGlobMatch/foo*_foobar2132=== CONT TestGlobMatch/refs/heads/*_refs/tags/v1.02133=== CONT TestGlobMatch/*bar_foo2134=== CONT TestGlobMatch/*bar_foobar2135=== CONT TestGlobMatch/*/*_foo2136=== CONT TestGlobMatch/?oo_foo2137=== CONT TestGlobMatch/fo?_fooo2138=== CONT TestGlobMatch/foo*_bar2139=== CONT TestGlobMatch/*/*_foo/bar2140=== CONT TestGlobMatch/*_anything2141=== CONT TestGlobMatch/foo*bar_foobarbaz2142=== CONT TestGlobMatch/foo*_foo2143=== CONT TestGlobMatch/refs/heads/*_refs/heads/main2144=== CONT TestGlobMatch/foo*bar_foobar2145--- PASS: TestGlobMatch (0.01s)2146 --- PASS: TestGlobMatch/foo_foo (0.00s)2147 --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)2148 --- PASS: TestGlobMatch/*bar_bar (0.00s)2149 --- PASS: TestGlobMatch/*_ (0.00s)2150 --- PASS: TestGlobMatch/fo?_fo (0.00s)2151 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)2152 --- PASS: TestGlobMatch/fo?_foo (0.00s)2153 --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)2154 --- PASS: TestGlobMatch/foo_bar (0.00s)2155 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)2156 --- PASS: TestGlobMatch/?oo_boo (0.00s)2157 --- PASS: TestGlobMatch/foo*_foobar (0.00s)2158 --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)2159 --- PASS: TestGlobMatch/*bar_foo (0.00s)2160 --- PASS: TestGlobMatch/*bar_foobar (0.00s)2161 --- PASS: TestGlobMatch/*/*_foo (0.00s)2162 --- PASS: TestGlobMatch/?oo_foo (0.00s)2163 --- PASS: TestGlobMatch/fo?_fooo (0.00s)2164 --- PASS: TestGlobMatch/foo*_bar (0.00s)2165 --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)2166 --- PASS: TestGlobMatch/*_anything (0.00s)2167 --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)2168 --- PASS: TestGlobMatch/foo*_foo (0.00s)2169 --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)2170 --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)21712026/10/06 08:16:14 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:42977/oidc2172--- PASS: TestValidateToken_BoundClaimsMismatch (0.03s)21732026/10/06 08:16:14 INFO OIDC provider initialized name=kubernetes issuer=https://oidc.eks.invalid/id/ABC12321742026/10/06 08:16:14 INFO OIDC provider initialized name=kubernetes issuer=https://127.0.0.1:440072175--- PASS: TestValidateToken_KubernetesIssuerFromOwnToken (0.03s)2176--- PASS: TestValidateToken_KubernetesServiceAccount (0.04s)21772026/10/06 08:16:14 INFO OIDC provider initialized name=provider1 issuer=http://127.0.0.1:34225/oidc21782026/10/06 08:16:14 http: TLS handshake error from 127.0.0.1:57674: remote error: tls: bad certificate2179--- PASS: TestNewValidator_KubernetesRequiresCA (0.06s)2180--- PASS: TestValidateToken_NoMatchingProvider (0.07s)21812026/10/06 08:16:14 INFO OIDC provider initialized name=provider1 issuer=http://127.0.0.1:40967/oidc21822026/10/06 08:16:14 INFO OIDC provider initialized name=provider2 issuer=http://127.0.0.1:42053/oidc2183--- PASS: TestValidateToken_MultipleProviders (0.09s)21842026/10/06 08:16:14 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:43413/oidc2185--- PASS: TestScopes_Rules (0.09s)21862026/10/06 08:16:14 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:38819/oidc2187--- PASS: TestValidateToken_Expired (0.10s)21882026/10/06 08:16:14 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:42761/oidc2189--- PASS: TestPins_ReservedForMatchingRule (0.12s)21902026/10/06 08:16:14 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:44673/oidc2191--- PASS: TestScopes_LegacyProviderDefaultsToWrite (0.14s)21922026/10/06 08:16:14 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:40091/oidc21932026/10/06 08:16:14 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:41073/oidc2194--- PASS: TestValidateToken_WrongAudience (0.14s)2195--- PASS: TestPins_TopLevelShorthand (0.14s)21962026/10/06 08:16:14 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:36891/oidc2197--- PASS: TestValidateToken_ValidToken (0.17s)21982026/10/06 08:16:14 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:35893/oidc2199--- PASS: TestValidateToken_BoundSubjectMismatch (0.18s)2200PASS2201Running hook tests...2202=== RUN TestSendPathsEmpty2203=== PAUSE TestSendPathsEmpty2204=== RUN TestQueueEnqueueAndFetch2205=== PAUSE TestQueueEnqueueAndFetch2206=== RUN TestQueueDeduplication2207=== PAUSE TestQueueDeduplication2208=== RUN TestQueueRemove2209=== PAUSE TestQueueRemove2210=== RUN TestQueueFetchBatchLimit2211=== PAUSE TestQueueFetchBatchLimit2212=== RUN TestQueueRetryMovesToBack2213=== PAUSE TestQueueRetryMovesToBack2214=== RUN TestQueueFetchRemoveLifecycle2215=== PAUSE TestQueueFetchRemoveLifecycle2216=== RUN TestQueueConcurrentWriters2217=== PAUSE TestQueueConcurrentWriters2218=== RUN TestQueueRemoveLargeClosure2219=== PAUSE TestQueueRemoveLargeClosure2220=== RUN TestServerClientIntegration2221=== PAUSE TestServerClientIntegration2222=== RUN TestServerQueueError2223=== PAUSE TestServerQueueError2224=== RUN TestGetListenerSocketActivation2225 server_test.go:210: === RUN TestGetListenerSocketActivation2226 --- PASS: TestGetListenerSocketActivation (0.00s)2227 PASS2228 2229--- PASS: TestGetListenerSocketActivation (0.01s)2230=== RUN TestDrainIsolatesPoisonPath2231=== PAUSE TestDrainIsolatesPoisonPath2232=== RUN TestRunNotBlockedByPoisonHead2233=== PAUSE TestRunNotBlockedByPoisonHead2234=== RUN TestDrainGivesUpWhenServerDown2235=== PAUSE TestDrainGivesUpWhenServerDown2236=== RUN TestFailedPathPrunedByLaterClosure2237=== PAUSE TestFailedPathPrunedByLaterClosure2238=== RUN TestWorkerUploadsAndRemoves2239=== PAUSE TestWorkerUploadsAndRemoves2240=== RUN TestWorkerSkipsGCdPaths2241=== PAUSE TestWorkerSkipsGCdPaths2242=== RUN TestWorkerPrunesClosureDeps2243=== PAUSE TestWorkerPrunesClosureDeps2244=== RUN TestDrainTimeout2245=== PAUSE TestDrainTimeout2246=== CONT TestSendPathsEmpty2247=== CONT TestWorkerSkipsGCdPaths2248--- PASS: TestSendPathsEmpty (0.00s)2249=== CONT TestDrainTimeout2250=== CONT TestDrainGivesUpWhenServerDown2251=== CONT TestRunNotBlockedByPoisonHead2252=== CONT TestDrainIsolatesPoisonPath2253=== CONT TestQueueRetryMovesToBack2254=== CONT TestServerClientIntegration2255=== CONT TestQueueRemoveLargeClosure2256=== CONT TestQueueConcurrentWriters2257=== CONT TestQueueFetchRemoveLifecycle2258=== CONT TestServerQueueError2259--- PASS: TestServerClientIntegration (0.00s)22602026/10/06 08:16:14 ERROR Failed to queue paths error="permission denied" count=12261=== CONT TestQueueDeduplication2262=== CONT TestQueueEnqueueAndFetch2263=== CONT TestWorkerUploadsAndRemoves2264=== CONT TestWorkerPrunesClosureDeps2265=== CONT TestQueueFetchBatchLimit2266=== CONT TestFailedPathPrunedByLaterClosure2267=== CONT TestQueueRemove2268--- PASS: TestServerQueueError (0.00s)22692026/10/06 08:16:14 INFO Uploading batch count=122702026/10/06 08:16:14 ERROR Upload failed error="upload failed" count=122712026/10/06 08:16:14 INFO Uploading batch count=422722026/10/06 08:16:14 ERROR Upload failed error="upload failed" count=422732026/10/06 08:16:14 INFO Upload queue status pending=222742026/10/06 08:16:14 INFO Upload queue status pending=222752026/10/06 08:16:14 INFO Upload queue status pending=222762026/10/06 08:16:14 INFO Uploading batch count=122772026/10/06 08:16:14 INFO Uploading batch count=222782026/10/06 08:16:14 ERROR Upload failed error="upload failed" count=22279--- PASS: TestQueueFetchBatchLimit (0.01s)22802026/10/06 08:16:14 INFO Uploading batch count=222812026/10/06 08:16:14 INFO Upload queue status pending=322822026/10/06 08:16:14 INFO Uploading batch count=122832026/10/06 08:16:14 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown1449273253/002/a2284--- PASS: TestQueueDeduplication (0.02s)22852026/10/06 08:16:14 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainIsolatesPoisonPath3581279406/002/bbb22862026/10/06 08:16:14 INFO Uploading batch count=122872026/10/06 08:16:14 ERROR Upload failed error="upload failed" count=122882026/10/06 08:16:14 INFO Uploading batch count=222892026/10/06 08:16:14 INFO Uploading batch count=122902026/10/06 08:16:14 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths753379545/002/nonexistent2291--- PASS: TestQueueRetryMovesToBack (0.02s)2292--- PASS: TestQueueFetchRemoveLifecycle (0.02s)22932026/10/06 08:16:14 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown1449273253/002/b2294--- PASS: TestQueueEnqueueAndFetch (0.02s)2295--- PASS: TestQueueRemove (0.02s)22962026/10/06 08:16:14 INFO Uploading batch count=122972026/10/06 08:16:14 INFO Uploading batch count=222982026/10/06 08:16:14 ERROR Upload failed error="upload failed" count=222992026/10/06 08:16:14 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown1449273253/002/c23002026/10/06 08:16:14 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown1449273253/002/d23012026/10/06 08:16:14 INFO Uploading batch count=123022026/10/06 08:16:14 ERROR Upload failed error="upload failed" count=123032026/10/06 08:16:14 INFO Uploading batch count=223042026/10/06 08:16:14 ERROR Upload failed error="upload failed" count=223052026/10/06 08:16:14 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown1449273253/002/e23062026/10/06 08:16:14 INFO Uploading batch count=123072026/10/06 08:16:14 ERROR Upload failed error="upload failed" count=12308--- PASS: TestFailedPathPrunedByLaterClosure (0.02s)23092026/10/06 08:16:14 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown1449273253/002/f23102026/10/06 08:16:14 INFO Uploading batch count=123112026/10/06 08:16:14 ERROR Upload failed error="upload failed" count=123122026/10/06 08:16:14 ERROR Drain finished with paths left in queue remaining=1023132026/10/06 08:16:14 ERROR Drain finished with paths left in queue remaining=12314--- PASS: TestDrainIsolatesPoisonPath (0.03s)2315--- PASS: TestDrainGivesUpWhenServerDown (0.03s)2316--- PASS: TestWorkerSkipsGCdPaths (0.04s)2317--- PASS: TestWorkerUploadsAndRemoves (0.03s)2318--- PASS: TestWorkerPrunesClosureDeps (0.04s)2319--- PASS: TestQueueRemoveLargeClosure (0.09s)23202026/10/06 08:16:14 ERROR Upload failed error="context deadline exceeded" count=223212026/10/06 08:16:14 ERROR Drain finished with paths left in queue remaining=42322--- PASS: TestDrainTimeout (0.22s)2323--- PASS: TestQueueConcurrentWriters (0.38s)23242026/10/06 08:16:15 INFO Uploading batch count=123252026/10/06 08:16:15 INFO Uploading batch count=123262026/10/06 08:16:15 INFO Uploading batch count=123272026/10/06 08:16:15 ERROR Upload failed error="upload failed" count=123282026/10/06 08:16:15 INFO Uploading batch count=123292026/10/06 08:16:15 ERROR Upload failed error="upload failed" count=123302026/10/06 08:16:15 INFO Uploading batch count=123312026/10/06 08:16:15 ERROR Upload failed error="upload failed" count=123322026/10/06 08:16:15 INFO Uploading batch count=123332026/10/06 08:16:15 ERROR Upload failed error="upload failed" count=123342026/10/06 08:16:15 ERROR Drain finished with paths left in queue remaining=12335--- PASS: TestRunNotBlockedByPoisonHead (1.03s)2336PASS2337Running signing tests...2338=== RUN TestExternalSigner2339=== PAUSE TestExternalSigner2340=== RUN TestExternalSignerWrongPublicKey2341=== PAUSE TestExternalSignerWrongPublicKey2342=== RUN TestExternalSignerErrors2343=== PAUSE TestExternalSignerErrors2344=== RUN TestExternalSignerRestartsAfterCrash2345=== PAUSE TestExternalSignerRestartsAfterCrash2346=== RUN TestExternalSignerProgramErrorKeepsProcess2347=== PAUSE TestExternalSignerProgramErrorKeepsProcess2348=== RUN TestExternalSignerClose2349=== PAUSE TestExternalSignerClose2350=== RUN TestExternalSignerRejectsInvalidPublicKey2351=== PAUSE TestExternalSignerRejectsInvalidPublicKey2352=== RUN TestExternalSignerCancellation2353=== PAUSE TestExternalSignerCancellation2354=== RUN TestGenerateFingerprint2355=== PAUSE TestGenerateFingerprint2356=== RUN TestParseSigningKey2357=== PAUSE TestParseSigningKey2358=== RUN TestSign2359=== PAUSE TestSign2360=== RUN TestSignBatch2361=== PAUSE TestSignBatch2362=== RUN TestNilKeyPublicKey2363=== PAUSE TestNilKeyPublicKey2364=== RUN TestSignErrors2365=== PAUSE TestSignErrors2366=== CONT TestExternalSigner2367=== CONT TestExternalSignerCancellation2368=== CONT TestExternalSignerProgramErrorKeepsProcess2369=== CONT TestSignBatch2370=== CONT TestSignErrors2371=== CONT TestNilKeyPublicKey2372=== CONT TestParseSigningKey2373=== RUN TestParseSigningKey/valid_32-byte_key2374=== PAUSE TestParseSigningKey/valid_32-byte_key2375=== CONT TestSign2376=== CONT TestExternalSignerRejectsInvalidPublicKey2377=== CONT TestExternalSignerClose2378=== CONT TestExternalSignerErrors2379=== CONT TestExternalSignerRestartsAfterCrash2380=== CONT TestGenerateFingerprint2381=== CONT TestExternalSignerWrongPublicKey2382--- PASS: TestNilKeyPublicKey (0.00s)2383=== RUN TestExternalSignerRejectsInvalidPublicKey/#002384=== RUN TestParseSigningKey/valid_32-byte_key_with_different_name2385=== PAUSE TestParseSigningKey/valid_32-byte_key_with_different_name2386=== RUN TestParseSigningKey/no_colon2387=== RUN TestExternalSignerErrors/exit_at_startup2388=== PAUSE TestExternalSignerRejectsInvalidPublicKey/#002389=== RUN TestGenerateFingerprint/basic_with_references2390=== PAUSE TestGenerateFingerprint/basic_with_references2391=== RUN TestGenerateFingerprint/no_references2392=== PAUSE TestGenerateFingerprint/no_references2393=== PAUSE TestParseSigningKey/no_colon2394=== PAUSE TestExternalSignerErrors/exit_at_startup2395=== RUN TestExternalSignerErrors/wrong_shape2396=== PAUSE TestExternalSignerErrors/wrong_shape2397=== RUN TestExternalSignerErrors/wrong_signature_name2398=== PAUSE TestExternalSignerErrors/wrong_signature_name2399=== RUN TestExternalSignerErrors/exit_on_request2400=== PAUSE TestExternalSignerErrors/exit_on_request2401=== RUN TestExternalSignerRejectsInvalidPublicKey/no-colon2402=== PAUSE TestExternalSignerRejectsInvalidPublicKey/no-colon2403=== RUN TestSignErrors/nil_key2404=== PAUSE TestSignErrors/nil_key2405=== RUN TestGenerateFingerprint/unsorted_references_get_sorted2406=== PAUSE TestGenerateFingerprint/unsorted_references_get_sorted2407=== RUN TestParseSigningKey/empty_name2408=== RUN TestExternalSignerErrors/wrong_id2409=== RUN TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=2410=== PAUSE TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=2411=== RUN TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!2412=== PAUSE TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!2413=== RUN TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==2414=== PAUSE TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==2415=== CONT TestExternalSignerRejectsInvalidPublicKey/#002416=== CONT TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=2417--- PASS: TestSign (0.00s)2418--- PASS: TestSignBatch (0.00s)2419=== CONT TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!2420=== RUN TestSignErrors/nil_narinfo2421=== PAUSE TestSignErrors/nil_narinfo2422=== RUN TestGenerateFingerprint/invalid_nar_hash_prefix2423=== RUN TestSignErrors/invalid_narinfo2424=== PAUSE TestSignErrors/invalid_narinfo2425=== PAUSE TestGenerateFingerprint/invalid_nar_hash_prefix2426=== PAUSE TestExternalSignerErrors/wrong_id2427=== CONT TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==2428=== CONT TestExternalSignerRejectsInvalidPublicKey/no-colon2429--- PASS: TestExternalSignerRejectsInvalidPublicKey (0.00s)2430 --- PASS: TestExternalSignerRejectsInvalidPublicKey/#00 (0.00s)2431 --- PASS: TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA= (0.00s)2432 --- PASS: TestExternalSignerRejectsInvalidPublicKey/test-key:invalid! (0.00s)2433 --- PASS: TestExternalSignerRejectsInvalidPublicKey/test-key:YQ== (0.00s)2434 --- PASS: TestExternalSignerRejectsInvalidPublicKey/no-colon (0.00s)2435=== PAUSE TestParseSigningKey/empty_name2436=== RUN TestParseSigningKey/invalid_base642437=== PAUSE TestParseSigningKey/invalid_base642438=== RUN TestParseSigningKey/wrong_length2439=== PAUSE TestParseSigningKey/wrong_length2440=== CONT TestParseSigningKey/valid_32-byte_key2441=== CONT TestParseSigningKey/invalid_base642442=== RUN TestSignErrors/canceled2443=== PAUSE TestSignErrors/canceled2444=== CONT TestSignErrors/nil_key2445=== RUN TestGenerateFingerprint/invalid_nar_hash_length2446=== CONT TestSignErrors/canceled2447=== RUN TestExternalSignerErrors/wrong_signatures_shape2448=== PAUSE TestExternalSignerErrors/wrong_signatures_shape2449=== CONT TestParseSigningKey/no_colon2450=== CONT TestParseSigningKey/empty_name2451=== CONT TestParseSigningKey/wrong_length2452=== CONT TestParseSigningKey/valid_32-byte_key_with_different_name2453--- PASS: TestParseSigningKey (0.00s)2454 --- PASS: TestParseSigningKey/valid_32-byte_key (0.00s)2455 --- PASS: TestParseSigningKey/invalid_base64 (0.00s)2456 --- PASS: TestParseSigningKey/no_colon (0.00s)2457 --- PASS: TestParseSigningKey/empty_name (0.00s)2458 --- PASS: TestParseSigningKey/wrong_length (0.00s)2459 --- PASS: TestParseSigningKey/valid_32-byte_key_with_different_name (0.00s)2460=== CONT TestSignErrors/invalid_narinfo2461=== CONT TestSignErrors/nil_narinfo2462--- PASS: TestSignErrors (0.00s)2463 --- PASS: TestSignErrors/nil_key (0.00s)2464 --- PASS: TestSignErrors/canceled (0.00s)2465 --- PASS: TestSignErrors/invalid_narinfo (0.00s)2466 --- PASS: TestSignErrors/nil_narinfo (0.00s)2467=== PAUSE TestGenerateFingerprint/invalid_nar_hash_length2468=== RUN TestGenerateFingerprint/invalid_store_path_prefix2469=== PAUSE TestGenerateFingerprint/invalid_store_path_prefix2470=== RUN TestExternalSignerErrors/unrequested_key2471=== RUN TestGenerateFingerprint/invalid_reference_prefix2472=== PAUSE TestGenerateFingerprint/invalid_reference_prefix2473=== CONT TestGenerateFingerprint/basic_with_references2474=== CONT TestGenerateFingerprint/unsorted_references_get_sorted2475=== PAUSE TestExternalSignerErrors/unrequested_key2476--- PASS: TestExternalSignerWrongPublicKey (0.00s)2477=== CONT TestGenerateFingerprint/invalid_nar_hash_length2478=== RUN TestExternalSignerErrors/program_error2479=== PAUSE TestExternalSignerErrors/program_error2480=== CONT TestGenerateFingerprint/invalid_reference_prefix2481--- PASS: TestExternalSignerProgramErrorKeepsProcess (0.00s)2482=== CONT TestGenerateFingerprint/invalid_store_path_prefix2483=== CONT TestGenerateFingerprint/invalid_nar_hash_prefix2484=== CONT TestGenerateFingerprint/no_references2485--- PASS: TestGenerateFingerprint (0.00s)2486 --- PASS: TestGenerateFingerprint/basic_with_references (0.00s)2487 --- PASS: TestGenerateFingerprint/unsorted_references_get_sorted (0.00s)2488 --- PASS: TestGenerateFingerprint/invalid_nar_hash_length (0.00s)2489 --- PASS: TestGenerateFingerprint/invalid_reference_prefix (0.00s)2490 --- PASS: TestGenerateFingerprint/invalid_store_path_prefix (0.00s)2491 --- PASS: TestGenerateFingerprint/invalid_nar_hash_prefix (0.00s)2492 --- PASS: TestGenerateFingerprint/no_references (0.00s)2493=== RUN TestExternalSignerErrors/invalid_JSON2494=== PAUSE TestExternalSignerErrors/invalid_JSON2495=== RUN TestExternalSignerErrors/null2496=== PAUSE TestExternalSignerErrors/null2497=== RUN TestExternalSignerErrors/null_signatures2498=== PAUSE TestExternalSignerErrors/null_signatures2499=== RUN TestExternalSignerErrors/invalid_signature_base642500=== PAUSE TestExternalSignerErrors/invalid_signature_base642501=== RUN TestExternalSignerErrors/wrong_signature_length2502=== PAUSE TestExternalSignerErrors/wrong_signature_length2503=== RUN TestExternalSignerErrors/missing_signatures2504=== PAUSE TestExternalSignerErrors/missing_signatures2505=== RUN TestExternalSignerErrors/too_few_signatures2506=== PAUSE TestExternalSignerErrors/too_few_signatures2507--- PASS: TestExternalSignerClose (0.00s)2508=== RUN TestExternalSignerErrors/missing_signature_name2509=== PAUSE TestExternalSignerErrors/missing_signature_name2510=== CONT TestExternalSignerErrors/exit_at_startup2511=== CONT TestExternalSignerErrors/null2512=== CONT TestExternalSignerErrors/invalid_JSON2513=== CONT TestExternalSignerErrors/missing_signature_name2514=== CONT TestExternalSignerErrors/too_few_signatures2515=== CONT TestExternalSignerErrors/missing_signatures2516=== CONT TestExternalSignerErrors/wrong_signature_length2517=== CONT TestExternalSignerErrors/invalid_signature_base642518=== CONT TestExternalSignerErrors/null_signatures2519=== CONT TestExternalSignerErrors/wrong_id2520=== CONT TestExternalSignerErrors/program_error2521=== CONT TestExternalSignerErrors/unrequested_key2522=== CONT TestExternalSignerErrors/wrong_signatures_shape2523=== CONT TestExternalSignerErrors/exit_on_request2524=== CONT TestExternalSignerErrors/wrong_signature_name2525=== CONT TestExternalSignerErrors/wrong_shape2526--- PASS: TestExternalSigner (0.01s)2527--- PASS: TestExternalSignerRestartsAfterCrash (0.01s)2528--- PASS: TestExternalSignerErrors (0.00s)2529 --- PASS: TestExternalSignerErrors/exit_at_startup (0.00s)2530 --- PASS: TestExternalSignerErrors/invalid_JSON (0.00s)2531 --- PASS: TestExternalSignerErrors/null (0.00s)2532 --- PASS: TestExternalSignerErrors/too_few_signatures (0.00s)2533 --- PASS: TestExternalSignerErrors/missing_signature_name (0.01s)2534 --- PASS: TestExternalSignerErrors/wrong_id (0.01s)2535 --- PASS: TestExternalSignerErrors/wrong_signature_length (0.01s)2536 --- PASS: TestExternalSignerErrors/invalid_signature_base64 (0.01s)2537 --- PASS: TestExternalSignerErrors/exit_on_request (0.01s)2538 --- PASS: TestExternalSignerErrors/wrong_signatures_shape (0.01s)2539 --- PASS: TestExternalSignerErrors/wrong_shape (0.00s)2540 --- PASS: TestExternalSignerErrors/missing_signatures (0.01s)2541 --- PASS: TestExternalSignerErrors/unrequested_key (0.01s)2542 --- PASS: TestExternalSignerErrors/null_signatures (0.01s)2543 --- PASS: TestExternalSignerErrors/wrong_signature_name (0.00s)2544 --- PASS: TestExternalSignerErrors/program_error (0.01s)2545--- PASS: TestExternalSignerCancellation (0.02s)2546PASS2547Running OpenBao signer tests...2548=== RUN TestSign2549=== PAUSE TestSign2550=== RUN TestSignErrors2551=== PAUSE TestSignErrors2552=== RUN TestServe2553=== PAUSE TestServe2554=== RUN TestServeRejectsGarbage2555=== PAUSE TestServeRejectsGarbage2556=== CONT TestSign2557=== CONT TestServeRejectsGarbage2558=== CONT TestServe2559=== CONT TestSignErrors2560=== RUN TestSignErrors/http_error2561--- PASS: TestServeRejectsGarbage (0.00s)2562=== PAUSE TestSignErrors/http_error2563=== RUN TestSignErrors/invalid_json2564=== PAUSE TestSignErrors/invalid_json2565=== RUN TestSignErrors/no_prefix2566=== PAUSE TestSignErrors/no_prefix2567=== RUN TestSignErrors/invalid_base642568=== PAUSE TestSignErrors/invalid_base642569=== CONT TestSignErrors/http_error2570=== CONT TestSignErrors/no_prefix2571--- PASS: TestServe (0.00s)2572=== CONT TestSignErrors/invalid_base642573=== CONT TestSignErrors/invalid_json2574--- PASS: TestSignErrors (0.00s)2575 --- PASS: TestSignErrors/no_prefix (0.00s)2576 --- PASS: TestSignErrors/http_error (0.00s)2577 --- PASS: TestSignErrors/invalid_base64 (0.00s)2578 --- PASS: TestSignErrors/invalid_json (0.00s)2579--- PASS: TestSign (0.01s)2580PASS