niks3-go-unit-tests
checks.aarch64-linux.go-unit-tests
· build #364
· raw
1tribuchet: building on eliza2Running 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.04s)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 TestScriptTokenEmptyCommand100=== CONT TestFilterOversizedClosures101=== CONT TestClientSignaturesByStorePath102=== CONT TestScriptTokenBadJSON103=== CONT TestSetClientTLSDoesNotMutateDefaultTransport104=== CONT TestRateLimiterFeedback_400DoesNotCountAsSuccess105=== CONT TestRateLimiterFeedback106=== RUN TestRateLimiterFeedback/429_enables_limiter107=== CONT TestPathInfoCACompatibility108=== RUN TestPathInfoCACompatibility/null_ca_field109=== PAUSE TestPathInfoCACompatibility/null_ca_field110=== CONT TestParsePathInfoJSONMultiplePaths111=== CONT TestParsePathInfoJSON112=== RUN TestParsePathInfoJSON/Nix_format113=== PAUSE TestParsePathInfoJSON/Nix_format114=== CONT TestPathInfoHashCompatibility115=== RUN TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)116=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)117=== RUN TestPathInfoHashCompatibility/old_string_format_with_colon118=== CONT TestGetStorePathHash119=== RUN TestGetStorePathHash/valid_store_path120=== PAUSE TestGetStorePathHash/valid_store_path121=== CONT TestConvertHashToNix32122=== RUN TestConvertHashToNix32/SRI_format_to_Nix32123=== PAUSE TestConvertHashToNix32/SRI_format_to_Nix32124=== RUN TestConvertHashToNix32/already_Nix32_format125=== PAUSE TestConvertHashToNix32/already_Nix32_format126=== RUN TestConvertHashToNix32/invalid_format127=== CONT TestEncodeNixBase32WithRealHash128=== CONT TestScriptTokenEmptyToken129=== CONT TestEncodeNixBase32130=== RUN TestEncodeNixBase32/test_string_hash131=== PAUSE TestEncodeNixBase32/test_string_hash132=== RUN TestEncodeNixBase32/empty_input133=== PAUSE TestEncodeNixBase32/empty_input134=== CONT TestScriptTokenCachesUntilRefresh135=== CONT TestDumpPathWriterError136=== CONT TestDumpPathSingleFile137=== CONT TestDumpPathMatchesNix1382026/10/06 08:15:56 WARN Rate limiter enabled after throttle name=server-test rate=5139=== CONT TestUploadMultipart_SupersededByPeer140=== RUN TestUploadMultipart_SupersededByPeer/exists141=== PAUSE TestUploadMultipart_SupersededByPeer/exists142=== RUN TestUploadMultipart_SupersededByPeer/missing143=== PAUSE TestUploadMultipart_SupersededByPeer/missing144=== CONT TestPartSizeForNAR145=== RUN TestPartSizeForNAR/zero_stays_at_minimum146=== PAUSE TestPartSizeForNAR/zero_stays_at_minimum147=== RUN TestPartSizeForNAR/small_stays_at_minimum148=== PAUSE TestPartSizeForNAR/small_stays_at_minimum149=== RUN TestPartSizeForNAR/80_GiB_fits_at_minimum150=== PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum151=== RUN TestPartSizeForNAR/115_GiB_needs_larger_parts152=== PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts153=== RUN TestPartSizeForNAR/1_TiB154=== PAUSE TestPartSizeForNAR/1_TiB155=== RUN TestPartSizeForNAR/5_TiB_S3_max_object156=== PAUSE TestPartSizeForNAR/5_TiB_S3_max_object157=== RUN TestPartSizeForNAR/capped_at_5_GiB158=== PAUSE TestPartSizeForNAR/capped_at_5_GiB159=== CONT TestScriptTokenNoExpiryRerunsEveryCall160=== CONT TestFileTokenEmpty161=== CONT TestDoWithRetry_BodyReplayedViaGetBody162=== CONT TestSetClientTLS163--- PASS: TestScriptTokenEmptyCommand (0.00s)164=== CONT TestScriptTokenScriptFails165=== RUN TestFilterOversizedClosures/no_limit_keeps_everything166=== CONT TestDoServerRequestAttachesToken167=== CONT TestResolveStorePath168=== PAUSE TestRateLimiterFeedback/429_enables_limiter169=== RUN TestPathInfoCACompatibility/old_string_format_-_text170=== RUN TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths171=== RUN TestParsePathInfoJSON/Lix_format172=== PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon173=== RUN TestGetStorePathHash/basename_without_hyphen_should_error174=== PAUSE TestConvertHashToNix32/invalid_format175=== CONT TestUploadMultipart_PartsInParallel176=== CONT TestFileTokenMissing177--- PASS: TestClientSignaturesByStorePath (0.00s)178--- PASS: TestEncodeNixBase32WithRealHash (0.00s)179--- PASS: TestScriptTokenBadJSON (0.01s)180=== PAUSE TestFilterOversizedClosures/no_limit_keeps_everything181=== RUN TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped182=== PAUSE TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped183=== RUN TestFilterOversizedClosures/all_closures_skipped184--- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.01s)185=== CONT TestFileTokenReadsAndCaches186=== PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths187=== RUN TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths188--- PASS: TestScriptTokenEmptyToken (0.01s)189=== CONT TestRunGarbageCollection_NotFoundAfterLocalRun190--- PASS: TestFileTokenMissing (0.00s)191=== CONT TestCaseHackSuffix192--- PASS: TestFileTokenEmpty (0.00s)193=== CONT TestSetClientTLSErrors194--- PASS: TestFileTokenReadsAndCaches (0.00s)195=== CONT TestRunGarbageCollection_FinishedOnAnotherReplica196=== CONT TestStaticToken197=== PAUSE TestPathInfoCACompatibility/old_string_format_-_text198=== RUN TestRateLimiterFeedback/503_enables_limiter199--- PASS: TestStaticToken (0.00s)200=== RUN TestPathInfoCACompatibility/old_string_format_-_fixed_recursive201=== CONT TestStreamPushIsolatesFailures202=== PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive203=== PAUSE TestFilterOversizedClosures/all_closures_skipped204=== CONT TestStreamPushReportsEveryPath205=== PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths2062026/10/06 08:15:56 ERROR Upload failed error="bad path" count=3207=== PAUSE TestGetStorePathHash/basename_without_hyphen_should_error208=== RUN TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI209=== PAUSE TestRateLimiterFeedback/503_enables_limiter210=== RUN TestGetStorePathHash/hash_with_invalid_characters_should_error211=== RUN TestPathInfoCACompatibility/new_structured_format_-_text212=== RUN TestSetClientTLS/rejects_connection_without_client_cert213--- PASS: TestScriptTokenScriptFails (0.03s)214=== CONT TestStreamPushReportsSignatures215=== PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error216=== PAUSE TestSetClientTLS/rejects_connection_without_client_cert217=== RUN TestGetStorePathHash/hash_with_wrong_length_should_error218=== PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error219=== CONT TestStreamPushRequestLine220=== RUN TestSetClientTLS/succeeds_with_client_cert_and_CA221=== PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI222=== RUN TestPathInfoHashCompatibility/new_structured_format_with_sha512223=== PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512224=== PAUSE TestParsePathInfoJSON/Lix_format225=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_text2262026/10/06 08:15:56 ERROR Upload failed error=boom count=1227=== CONT TestShellSplit2282026/10/06 08:15:56 WARN Rate limiter enabled after throttle name=server-test rate=52292026/10/06 08:15:56 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:36703230=== CONT TestEncodeNixBase32/test_string_hash231=== CONT TestEncodeNixBase32/empty_input232=== CONT TestUploadMultipart_SupersededByPeer/exists233--- PASS: TestStreamPushIsolatesFailures (0.03s)234=== RUN TestSetClientTLSErrors/missing_cert_file235--- PASS: TestScriptTokenCachesUntilRefresh (0.04s)236--- PASS: TestStreamPushReportsEveryPath (0.03s)237--- PASS: TestShellSplit (0.00s)2382026/10/06 08:15:56 WARN Rate limiter backed off name=server-test rate=5239=== RUN TestRateLimiterFeedback/200_does_not_enable_limiter2402026/10/06 08:15:56 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:36703241=== PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter2422026/10/06 08:15:56 INFO Garbage collection started243=== CONT TestUploadMultipart_SupersededByPeer/missing2442026/10/06 08:15:56 ERROR Upload failed error=boom count=1245=== RUN TestRateLimiterFeedback/400_does_not_enable_limiter2462026/10/06 08:15:56 INFO Garbage collection started247=== CONT TestPartSizeForNAR/80_GiB_fits_at_minimum248=== CONT TestPartSizeForNAR/115_GiB_needs_larger_parts249=== CONT TestPartSizeForNAR/small_stays_at_minimum250=== PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA251=== CONT TestStreamPushGivesUpOnDeadServer252=== RUN TestSetClientTLS/preserves_debug_logging_transport253=== CONT TestShellSplitErrors2542026/10/06 08:15:56 ERROR Upload failed error="connection refused" count=202552026/10/06 08:15:56 ERROR Server seems unavailable, giving up on batch untried=17256=== CONT TestPartSizeForNAR/5_TiB_S3_max_object257=== CONT TestStreamPushBatchesUnderLoad258=== RUN TestParsePathInfoJSON/empty_input259=== PAUSE TestParsePathInfoJSON/empty_input260=== RUN TestPathInfoCACompatibility/new_structured_format_-_nar_method261=== PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method262=== CONT TestRegisterUploadedObjectReusesConnections263=== PAUSE TestSetClientTLSErrors/missing_cert_file264=== CONT TestConvertHashToNix32/already_Nix32_format265=== CONT TestFilterOversizedClosures/no_limit_keeps_everything266=== CONT TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped2672026/10/06 08:15:56 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=2000268=== RUN TestSetClientTLSErrors/missing_key_file269=== CONT TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths270=== PAUSE TestSetClientTLSErrors/missing_key_file271=== RUN TestSetClientTLSErrors/missing_ca_file272=== PAUSE TestSetClientTLSErrors/missing_ca_file273=== RUN TestSetClientTLSErrors/invalid_ca_file274=== PAUSE TestSetClientTLSErrors/invalid_ca_file275=== CONT TestPartSizeForNAR/zero_stays_at_minimum276=== CONT TestGetStorePathHash/valid_store_path277=== CONT TestGetStorePathHash/hash_with_wrong_length_should_error278=== CONT TestGetStorePathHash/hash_with_invalid_characters_should_error279=== CONT TestGetStorePathHash/basename_without_hyphen_should_error280=== CONT TestPathInfoHashCompatibility/new_structured_format_with_sha512281=== CONT TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI282=== CONT TestPathInfoCACompatibility/null_ca_field283=== CONT TestPathInfoCACompatibility/old_string_format_-_fixed_recursive284=== CONT TestPathInfoCACompatibility/new_structured_format_-_nar_method285=== CONT TestPathInfoCACompatibility/new_structured_format_-_text286=== CONT TestPathInfoCACompatibility/old_string_format_-_text287=== CONT TestSetClientTLSErrors/missing_cert_file288=== CONT TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)289=== CONT TestSetClientTLSErrors/missing_ca_file290=== CONT TestSetClientTLSErrors/missing_key_file2912026/10/06 08:15:56 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=0292=== CONT TestPathInfoHashCompatibility/old_string_format_with_colon293=== PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter294=== CONT TestRateLimiterFeedback/429_enables_limiter295=== CONT TestRateLimiterFeedback/400_does_not_enable_limiter296=== CONT TestRateLimiterFeedback/503_enables_limiter297=== CONT TestPartSizeForNAR/capped_at_5_GiB298=== PAUSE TestSetClientTLS/preserves_debug_logging_transport299=== CONT TestConvertHashToNix32/SRI_format_to_Nix32300=== RUN TestParsePathInfoJSON/whitespace_only301=== CONT TestConvertHashToNix32/invalid_format302--- PASS: TestEncodeNixBase32 (0.00s)303 --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)304 --- PASS: TestEncodeNixBase32/empty_input (0.00s)305=== CONT TestFilterOversizedClosures/all_closures_skipped306=== CONT TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths307=== CONT TestPartSizeForNAR/1_TiB308=== CONT TestSetClientTLSErrors/invalid_ca_file309=== CONT TestRateLimiterFeedback/200_does_not_enable_limiter310=== CONT TestSetClientTLS/rejects_connection_without_client_cert3112026/10/06 08:15:56 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=503122026/10/06 08:15:56 WARN Rate limiter enabled after throttle name=server-test rate=53132026/10/06 08:15:56 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:33869314--- PASS: TestDoServerRequestAttachesToken (0.03s)315--- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.03s)316--- PASS: TestResolveStorePath (0.03s)317--- PASS: TestStreamPushReportsSignatures (0.00s)318--- PASS: TestShellSplitErrors (0.00s)319--- PASS: TestStreamPushGivesUpOnDeadServer (0.00s)320=== PAUSE TestParsePathInfoJSON/whitespace_only321--- PASS: TestGetStorePathHash (0.04s)322 --- PASS: TestGetStorePathHash/valid_store_path (0.00s)323 --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)324 --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)325 --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)3262026/10/06 08:15:56 WARN Rate limiter enabled after throttle name=server-test rate=5327=== RUN TestParsePathInfoJSON/invalid_JSON3282026/10/06 08:15:56 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:37573329=== PAUSE TestParsePathInfoJSON/invalid_JSON330=== CONT TestParsePathInfoJSON/Nix_format331=== CONT TestParsePathInfoJSON/whitespace_only332--- PASS: TestUploadMultipart_SupersededByPeer (0.00s)333 --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.00s)334 --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.00s)3352026/10/06 08:15:56 WARN Rate limiter backed off name=server-test rate=5336--- PASS: TestRunGarbageCollection_NotFoundAfterLocalRun (0.03s)3372026/10/06 08:15:56 WARN Rate limiter backed off name=server-test rate=5338--- PASS: TestConvertHashToNix32 (0.01s)339 --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)340 --- PASS: TestConvertHashToNix32/invalid_format (0.00s)341 --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)342=== CONT TestParsePathInfoJSON/invalid_JSON3432026/10/06 08:15:56 WARN Garbage collection finished on another replica. Its statistics are not available here344=== CONT TestSetClientTLS/succeeds_with_client_cert_and_CA345--- PASS: TestPathInfoCACompatibility (0.04s)346 --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)347 --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)348 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)349 --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)350 --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)351--- PASS: TestRunGarbageCollection_FinishedOnAnotherReplica (0.03s)352--- PASS: TestFilterOversizedClosures (0.01s)353 --- PASS: TestFilterOversizedClosures/no_limit_keeps_everything (0.00s)354 --- PASS: TestFilterOversizedClosures/closure_with_oversized_dependency_is_skipped (0.00s)355 --- PASS: TestFilterOversizedClosures/all_closures_skipped (0.00s)356--- PASS: TestParsePathInfoJSONMultiplePaths (0.01s)357 --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)358 --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)359--- PASS: TestRateLimiterFeedback (0.04s)360 --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.00s)361 --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.00s)362 --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.00s)363 --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.00s)364--- PASS: TestDumpPathSingleFile (0.04s)365--- PASS: TestPartSizeForNAR (0.00s)366 --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)367 --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)368 --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)369 --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)370 --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)371 --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)372 --- PASS: TestPartSizeForNAR/1_TiB (0.00s)373--- PASS: TestPathInfoHashCompatibility (0.04s)374 --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)375 --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)376 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)377 --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)378=== CONT TestParsePathInfoJSON/empty_input379=== CONT TestParsePathInfoJSON/Lix_format380--- PASS: TestParsePathInfoJSON (0.04s)381 --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)382 --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)383 --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)384 --- PASS: TestParsePathInfoJSON/empty_input (0.00s)385 --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)386=== CONT TestSetClientTLS/preserves_debug_logging_transport387--- PASS: TestSetClientTLSErrors (0.03s)388 --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)389 --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)390 --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)391 --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.00s)392--- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.04s)3932026/10/06 08:15:56 http: TLS handshake error from 127.0.0.1:33176: remote error: tls: bad certificate394--- PASS: TestSetClientTLS (0.03s)395 --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)396 --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.01s)397 --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.01s)398--- PASS: TestRegisterUploadedObjectReusesConnections (0.02s)399--- PASS: TestStreamPushRequestLine (0.02s)400--- PASS: TestCaseHackSuffix (0.06s)401--- PASS: TestDumpPathWriterError (0.07s)402--- PASS: TestDumpPathMatchesNix (0.10s)403--- PASS: TestStreamPushBatchesUnderLoad (0.10s)404--- PASS: TestUploadMultipart_PartsInParallel (0.64s)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/postgres3385731665/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/postgres3385731665/data -l logfile start434435/build/postgres3385731665:5432 - no response4362026-10-06 08:15:58.256 UTC [128] LOG: starting PostgreSQL 18.6 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit4372026-10-06 08:15:58.257 UTC [128] LOG: listening on Unix socket "/build/postgres3385731665/.s.PGSQL.5432"4382026-10-06 08:15:58.262 UTC [135] LOG: database system was shut down at 2026-10-06 08:15:58 UTC4392026-10-06 08:15:58.267 UTC [128] LOG: database system is ready to accept connections440/build/postgres3385731665:5432 - accepting connections441{"timestamp":"2026-10-06T08:15:58.460104628Z","level":"ERROR","message":"HTTP request completed","event":"http_request_completed","component":"server","subsystem":"http","request_id":"afcf8990-1307-4564-9608-d7ac5bb65e53","trace_id":"unknown","span_id":"unknown","peer_addr":"127.0.0.1","method":"GET","uri":"/health/ready","status_code":503,"suppressed_errors":0,"duration_ms":0,"result":"server_error","target":"rustfs::server::http","filename":"rustfs/src/server/layer.rs","line_number":501,"threadName":"rustfs-worker","threadId":"ThreadId(205)"}442=== RUN TestService_AuthMiddleware443=== PAUSE TestService_AuthMiddleware444=== RUN TestService_AuthMiddleware_MTLSProxyHeader445=== PAUSE TestService_AuthMiddleware_MTLSProxyHeader446=== RUN TestService_AuthMiddleware_MTLSBoundSubjects447=== PAUSE TestService_AuthMiddleware_MTLSBoundSubjects448=== RUN TestService_ReadAuthMiddleware449=== PAUSE TestService_ReadAuthMiddleware450=== RUN TestService_AuthMiddleware_OIDC451=== PAUSE TestService_AuthMiddleware_OIDC452=== RUN TestService_RequireScope_OIDC453=== PAUSE TestService_RequireScope_OIDC454=== RUN TestService_ReadScope_PublicByDefault455=== PAUSE TestService_ReadScope_PublicByDefault456=== RUN TestCacheConfigHandler457=== PAUSE TestCacheConfigHandler458=== RUN TestCacheStatsHandler459=== PAUSE TestCacheStatsHandler460=== RUN TestClientCADerivations461=== PAUSE TestClientCADerivations462=== RUN TestClientErrorHandling463=== PAUSE TestClientErrorHandling464=== RUN TestClientIntegration465=== PAUSE TestClientIntegration466=== RUN TestClientMultipleUploads467=== PAUSE TestClientMultipleUploads468=== RUN TestClientWithDependencies469=== PAUSE TestClientWithDependencies470=== RUN TestClientSharedPathCommittedMidPush471=== PAUSE TestClientSharedPathCommittedMidPush472=== RUN TestPinProtectsFromGC473=== PAUSE TestPinProtectsFromGC474=== RUN TestClientPushesUseOnePush475=== PAUSE TestClientPushesUseOnePush476=== RUN TestClientFallsBackToClosures477=== PAUSE TestClientFallsBackToClosures478=== RUN TestConcurrentCommitsSharingObjectsDoNotDeadlock479=== PAUSE TestConcurrentCommitsSharingObjectsDoNotDeadlock480=== RUN TestResolveDBConnectionString481=== PAUSE TestResolveDBConnectionString482=== RUN TestConnectWaitsForAPeerMigration483=== PAUSE TestConnectWaitsForAPeerMigration484=== RUN TestLeadElectsOneAndHandsOver485=== PAUSE TestLeadElectsOneAndHandsOver486=== RUN TestLeadIncumbentWinsAfterRestart4872026/10/06 08:15:58 INFO lead: acquired remote=192.0.2.1:12344882026/10/06 08:15:59 INFO lead: released remote=192.0.2.1:12344892026/10/06 08:15:59 INFO lead: acquired remote=192.0.2.1:12344902026/10/06 08:16:00 INFO lead: released remote=192.0.2.1:1234491--- PASS: TestLeadIncumbentWinsAfterRestart (1.85s)492=== RUN TestLeadIncumbentAnnouncesAtOnce493=== PAUSE TestLeadIncumbentAnnouncesAtOnce494=== RUN TestLeadEndsOnShutdown495=== PAUSE TestLeadEndsOnShutdown496=== RUN TestLeadEndsWhenItsConnectionHangs4972026/10/06 08:16:00 INFO lead: acquired remote=192.0.2.1:12344982026-10-06 08:16:01.940 UTC [389] FATAL: terminating connection due to administrator command4992026/10/06 08:16:02 INFO lead: acquired remote=192.0.2.1:12345002026/10/06 08:16:03 WARN lead: lock connection lost error="timeout: context deadline exceeded"5012026/10/06 08:16:03 INFO lead: released remote=192.0.2.1:12345022026/10/06 08:16:03 INFO lead: released remote=192.0.2.1:1234503--- PASS: TestLeadEndsWhenItsConnectionHangs (2.92s)504=== RUN TestGCAdvisoryLockBlocksConcurrentRun505=== PAUSE TestGCAdvisoryLockBlocksConcurrentRun506=== RUN TestGCBugBareHashReferences507=== PAUSE TestGCBugBareHashReferences508=== RUN TestGCMetrics509=== PAUSE TestGCMetrics510=== RUN TestPushDedupSurvivesConcurrentGC511=== PAUSE TestPushDedupSurvivesConcurrentGC512=== RUN TestDeduplicatedObjectsRecordedAsPending513=== PAUSE TestDeduplicatedObjectsRecordedAsPending514=== RUN TestGCSweepSkipsPendingObjects515=== PAUSE TestGCSweepSkipsPendingObjects516=== RUN TestTombstonedObjectOfferedWithoutWaiting517=== PAUSE TestTombstonedObjectOfferedWithoutWaiting518=== RUN TestGCSweepDeliversEachKeyOnce519=== PAUSE TestGCSweepDeliversEachKeyOnce520=== RUN TestCreatePendingClosureVerifyS3FailureReleasesConnection521=== PAUSE TestCreatePendingClosureVerifyS3FailureReleasesConnection522=== RUN TestForceGCDuringPushOffersSweptObject523=== PAUSE TestForceGCDuringPushOffersSweptObject524=== RUN TestFailedS3DeleteKeepsTombstone525=== PAUSE TestFailedS3DeleteKeepsTombstone526=== RUN TestSweepSkipsRowsLockedByPush527=== PAUSE TestSweepSkipsRowsLockedByPush528=== RUN TestRegisterCompletedObjectNeedsPendingRow529=== PAUSE TestRegisterCompletedObjectNeedsPendingRow530=== RUN TestGetExistingObjectsLocksRows531=== PAUSE TestGetExistingObjectsLocksRows532=== RUN TestSweepRechecksPendingAfterLocking533=== PAUSE TestSweepRechecksPendingAfterLocking534=== RUN TestPushWaitsForSweepHoldingRow535=== PAUSE TestPushWaitsForSweepHoldingRow536=== RUN TestCommitRacingPendingCleanupKeepsObjects537=== PAUSE TestCommitRacingPendingCleanupKeepsObjects538=== RUN TestGCEndsOnShutdown539=== PAUSE TestGCEndsOnShutdown540=== RUN TestGCTaskStore_StartNew541=== PAUSE TestGCTaskStore_StartNew542=== RUN TestGCTaskStore_DeduplicateSameParams543=== PAUSE TestGCTaskStore_DeduplicateSameParams544=== RUN TestGCTaskStore_ConflictDifferentParams545=== PAUSE TestGCTaskStore_ConflictDifferentParams546=== RUN TestGCTaskStore_GetEmpty547=== PAUSE TestGCTaskStore_GetEmpty548=== RUN TestGCTaskStore_GetReturnsLatest549=== PAUSE TestGCTaskStore_GetReturnsLatest550=== RUN TestGCTaskStore_CompletedAllowsNewTask551=== PAUSE TestGCTaskStore_CompletedAllowsNewTask552=== RUN TestGCTaskStore_PhaseUpdates553=== PAUSE TestGCTaskStore_PhaseUpdates554=== RUN TestGCTaskStore_Fail555=== PAUSE TestGCTaskStore_Fail556=== RUN TestGracefulShutdownDrainsInflight557=== PAUSE TestGracefulShutdownDrainsInflight558=== RUN TestService_healthCheckHandler559=== PAUSE TestService_healthCheckHandler560=== RUN TestService_readinessHandler561=== PAUSE TestService_readinessHandler562=== RUN TestGenerateLandingPage563=== PAUSE TestGenerateLandingPage564=== RUN TestCacheConfigHandlerMaxNarSize565=== PAUSE TestCacheConfigHandlerMaxNarSize566=== RUN TestCreatePendingClosureRejectsOversizedNAR567=== PAUSE TestCreatePendingClosureRejectsOversizedNAR568=== RUN TestNARDeduplicationMetadataUploadBug569=== PAUSE TestNARDeduplicationMetadataUploadBug570=== RUN TestMetricsInventory571=== PAUSE TestMetricsInventory572=== RUN TestService_NativeMTLS573=== PAUSE TestService_NativeMTLS574=== RUN TestServerTLSConfig575=== PAUSE TestServerTLSConfig576=== RUN TestMultipartCleanup577=== PAUSE TestMultipartCleanup578=== RUN TestMultipartUploadAbortedWhenCancelledBeforeRecorded579=== PAUSE TestMultipartUploadAbortedWhenCancelledBeforeRecorded580=== RUN TestPendingCleanupUsesOneCutoff581=== PAUSE TestPendingCleanupUsesOneCutoff582=== RUN TestObjectStatsTrigger583=== PAUSE TestObjectStatsTrigger584=== RUN TestOrphanedObjectsGC585=== PAUSE TestOrphanedObjectsGC586=== RUN TestOrphanedObjectsGCStressTest587=== PAUSE TestOrphanedObjectsGCStressTest588=== RUN TestCreatePin_ReservedPins589=== PAUSE TestCreatePin_ReservedPins590=== RUN TestPresentReportsOnlyClosureRoots591=== PAUSE TestPresentReportsOnlyClosureRoots592=== RUN TestPresentNotReportedWhileGCDeletesClosure593=== PAUSE TestPresentNotReportedWhileGCDeletesClosure594=== RUN TestParseSingleRange595=== PAUSE TestParseSingleRange596=== RUN TestProxyHeadersOnlyTrustedOnSocket597=== PAUSE TestProxyHeadersOnlyTrustedOnSocket598=== RUN TestIsValidCachePath599=== PAUSE TestIsValidCachePath600=== RUN TestReadProxyNarinfo601=== PAUSE TestReadProxyNarinfo602=== RUN TestReadProxyNarinfoAlreadyDecompressed603=== PAUSE TestReadProxyNarinfoAlreadyDecompressed604=== RUN TestReadProxyNarStreaming605=== PAUSE TestReadProxyNarStreaming606=== RUN TestReadProxy404607=== PAUSE TestReadProxy404608=== RUN TestReadProxyInvalidPath609=== PAUSE TestReadProxyInvalidPath610=== RUN TestReadProxyHead611=== PAUSE TestReadProxyHead612=== RUN TestReadProxyOutlastsServerWriteTimeout613=== PAUSE TestReadProxyOutlastsServerWriteTimeout614=== RUN TestReadProxyConditionalGet615=== PAUSE TestReadProxyConditionalGet616=== RUN TestReadProxyRootRedirectsToIndexHTML617=== PAUSE TestReadProxyRootRedirectsToIndexHTML618=== RUN TestReadProxyDisabled619=== PAUSE TestReadProxyDisabled620=== RUN TestReadRedirectNar621=== PAUSE TestReadRedirectNar622=== RUN TestReadRedirectKeepsNarinfoProxied623=== PAUSE TestReadRedirectKeepsNarinfoProxied624=== RUN TestReadProxyRangeRequest625=== PAUSE TestReadProxyRangeRequest626=== RUN TestReadRedirectUsesPublicS3URL627=== PAUSE TestReadRedirectUsesPublicS3URL628=== RUN TestPush_OverlappingRootsStoreOneRowPerKey629=== PAUSE TestPush_OverlappingRootsStoreOneRowPerKey630=== RUN TestPush_CompleteCommitsEveryRoot631=== PAUSE TestPush_CompleteCommitsEveryRoot632=== RUN TestPush_SkippedKeySurvivesGCBeforeCommit633=== PAUSE TestPush_SkippedKeySurvivesGCBeforeCommit634=== RUN TestPush_RejectsBadRequests635=== PAUSE TestPush_RejectsBadRequests636=== RUN TestPush_SignsNarinfosOfItsPendingObjects637=== PAUSE TestPush_SignsNarinfosOfItsPendingObjects638=== RUN TestRedundantMultipartUpload639=== PAUSE TestRedundantMultipartUpload640=== RUN TestCompleteMultipartUpload_ErrorButObjectExists641=== PAUSE TestCompleteMultipartUpload_ErrorButObjectExists642=== RUN TestCompletedNarNotReofferedAcrossClosures643=== PAUSE TestCompletedNarNotReofferedAcrossClosures644=== RUN TestPresignedUploadRegisteredBeforeCommit645=== PAUSE TestPresignedUploadRegisteredBeforeCommit646=== RUN TestService_Rustfstest647=== PAUSE TestService_Rustfstest648=== RUN TestParseSize649=== PAUSE TestParseSize650=== RUN TestSkippedUploadsHandler651=== PAUSE TestSkippedUploadsHandler652=== RUN TestSystemdListenerNotActivated653--- PASS: TestSystemdListenerNotActivated (0.00s)654=== RUN TestWatchdogBeatsWhenHealthy655--- PASS: TestWatchdogBeatsWhenHealthy (0.02s)656=== RUN TestWatchdogSkipsWhenUnhealthy6572026/10/06 08:16:03 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6582026/10/06 08:16:03 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6592026/10/06 08:16:03 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6602026/10/06 08:16:03 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6612026/10/06 08:16:03 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6622026/10/06 08:16:03 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6632026/10/06 08:16:03 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6642026/10/06 08:16:03 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6652026/10/06 08:16:03 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"6662026/10/06 08:16:03 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"667--- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)668=== RUN TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle669=== PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle670=== RUN TestProxyWriteTimeout671=== PAUSE TestProxyWriteTimeout672=== RUN TestPendingClosureWriteTimeout673=== PAUSE TestPendingClosureWriteTimeout674=== RUN TestIsValidUploadKey675=== PAUSE TestIsValidUploadKey676=== RUN TestUploadHandlersRejectInvalidKeys677=== PAUSE TestUploadHandlersRejectInvalidKeys678=== RUN TestUploadHandlersRejectOversizedBody679=== PAUSE TestUploadHandlersRejectOversizedBody680=== RUN TestService_cleanupPendingClosuresHandler681=== PAUSE TestService_cleanupPendingClosuresHandler682=== RUN TestService_createPendingClosureHandler683=== PAUSE TestService_createPendingClosureHandler684=== RUN TestService_verifyS3Integrity685=== PAUSE TestService_verifyS3Integrity686=== RUN TestCompleteMultipartUnregistered687=== PAUSE TestCompleteMultipartUnregistered688=== RUN TestCreatePendingClosure_SmallNARUsesSimplePUT689=== PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT690=== CONT TestPendingClosureWriteTimeout691=== CONT TestSweepRechecksPendingAfterLocking692=== CONT TestPush_RejectsBadRequests693=== RUN TestPendingClosureWriteTimeout/empty694=== CONT TestGCTaskStore_GetEmpty695--- PASS: TestGCTaskStore_GetEmpty (0.00s)696=== CONT TestService_createPendingClosureHandler697=== CONT TestProxyWriteTimeout698=== RUN TestProxyWriteTimeout/narinfo699=== PAUSE TestProxyWriteTimeout/narinfo700=== RUN TestProxyWriteTimeout/1_GiB_nar701=== PAUSE TestProxyWriteTimeout/1_GiB_nar702=== RUN TestProxyWriteTimeout/10_GiB_nar703=== CONT TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle704=== CONT TestSkippedUploadsHandler705=== CONT TestParseSize706--- PASS: TestParseSize (0.00s)707=== CONT TestPresentReportsOnlyClosureRoots708=== CONT TestService_Rustfstest709=== CONT TestPresignedUploadRegisteredBeforeCommit7102026/10/06 08:16:03 INFO Client skipped oversized paths paths=3 nar_bytes=5000000000711=== CONT TestCompletedNarNotReofferedAcrossClosures712=== CONT TestCompleteMultipartUpload_ErrorButObjectExists713=== CONT TestRedundantMultipartUpload714=== CONT TestPush_SignsNarinfosOfItsPendingObjects715=== CONT TestGCTaskStore_ConflictDifferentParams716--- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)717=== CONT TestService_cleanupPendingClosuresHandler718=== CONT TestGCTaskStore_DeduplicateSameParams719--- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)720=== CONT TestPush_SkippedKeySurvivesGCBeforeCommit721=== CONT TestGCTaskStore_StartNew722--- PASS: TestGCTaskStore_StartNew (0.00s)723=== CONT TestUploadHandlersRejectOversizedBody724=== CONT TestGCEndsOnShutdown725=== RUN TestGCEndsOnShutdown/before_the_run726=== PAUSE TestGCEndsOnShutdown/before_the_run727=== RUN TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete728=== PAUSE TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete729=== CONT TestCommitRacingPendingCleanupKeepsObjects730=== CONT TestPushWaitsForSweepHoldingRow731=== CONT TestService_AuthMiddleware732=== CONT TestCreatePendingClosure_SmallNARUsesSimplePUT733=== CONT TestCompleteMultipartUnregistered734=== CONT TestService_verifyS3Integrity735=== PAUSE TestPendingClosureWriteTimeout/empty736=== PAUSE TestProxyWriteTimeout/10_GiB_nar737=== RUN TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark738=== PAUSE TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark739=== RUN TestPendingClosureWriteTimeout/400_objects740=== PAUSE TestPendingClosureWriteTimeout/400_objects741=== RUN TestPendingClosureWriteTimeout/670k_objects742=== PAUSE TestPendingClosureWriteTimeout/670k_objects743=== RUN TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline744=== PAUSE TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline745=== CONT TestUploadHandlersRejectInvalidKeys746=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info747=== RUN TestProxyWriteTimeout/unknown_size748=== CONT TestPush_CompleteCommitsEveryRoot749=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info750=== RUN TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal751=== PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal752=== PAUSE TestProxyWriteTimeout/unknown_size753=== RUN TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key754=== PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key755=== CONT TestPush_OverlappingRootsStoreOneRowPerKey756=== RUN TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key757=== PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key758=== CONT TestIsValidUploadKey759=== RUN TestIsValidUploadKey/narinfo760=== PAUSE TestIsValidUploadKey/narinfo761--- PASS: TestSkippedUploadsHandler (0.07s)762=== RUN TestIsValidUploadKey/nar_zst763=== PAUSE TestIsValidUploadKey/nar_zst764=== RUN TestIsValidUploadKey/nar_xz765=== CONT TestReadRedirectUsesPublicS3URL766=== PAUSE TestIsValidUploadKey/nar_xz767=== RUN TestIsValidUploadKey/nar_plain768=== PAUSE TestIsValidUploadKey/nar_plain769=== RUN TestIsValidUploadKey/listing770=== PAUSE TestIsValidUploadKey/listing771=== RUN TestIsValidUploadKey/build_log772=== PAUSE TestIsValidUploadKey/build_log773=== RUN TestIsValidUploadKey/build_log_home-manager_file774=== PAUSE TestIsValidUploadKey/build_log_home-manager_file775=== RUN TestIsValidUploadKey/build_log_plus_in_name776=== PAUSE TestIsValidUploadKey/build_log_plus_in_name777=== RUN TestIsValidUploadKey/build_log_question_mark778=== PAUSE TestIsValidUploadKey/build_log_question_mark779=== RUN TestIsValidUploadKey/build_log_equals780=== PAUSE TestIsValidUploadKey/build_log_equals781=== RUN TestIsValidUploadKey/realisation782=== PAUSE TestIsValidUploadKey/realisation783=== RUN TestIsValidUploadKey/realisation_plus_in_output784=== PAUSE TestIsValidUploadKey/realisation_plus_in_output785=== RUN TestIsValidUploadKey/nix-cache-info786=== PAUSE TestIsValidUploadKey/nix-cache-info787=== RUN TestIsValidUploadKey/index.html788=== PAUSE TestIsValidUploadKey/index.html789=== RUN TestIsValidUploadKey/narinfo_key,_nar_type790=== PAUSE TestIsValidUploadKey/narinfo_key,_nar_type791=== RUN TestIsValidUploadKey/nar_key,_narinfo_type792=== PAUSE TestIsValidUploadKey/nar_key,_narinfo_type793=== RUN TestIsValidUploadKey/listing_key,_narinfo_type794=== PAUSE TestIsValidUploadKey/listing_key,_narinfo_type795=== RUN TestIsValidUploadKey/traversal796=== PAUSE TestIsValidUploadKey/traversal797=== RUN TestIsValidUploadKey/traversal_nar798=== PAUSE TestIsValidUploadKey/traversal_nar799=== RUN TestIsValidUploadKey/absolute800=== PAUSE TestIsValidUploadKey/absolute801=== RUN TestIsValidUploadKey/empty_key802=== PAUSE TestIsValidUploadKey/empty_key803=== RUN TestIsValidUploadKey/unknown_type804=== PAUSE TestIsValidUploadKey/unknown_type805=== CONT TestResolveDBConnectionString806=== RUN TestResolveDBConnectionString/flag_wins807=== PAUSE TestResolveDBConnectionString/flag_wins808=== RUN TestResolveDBConnectionString/file_when_flag_empty809=== PAUSE TestResolveDBConnectionString/file_when_flag_empty810=== RUN TestResolveDBConnectionString/missing_file_is_an_error811=== PAUSE TestResolveDBConnectionString/missing_file_is_an_error812=== RUN TestResolveDBConnectionString/PGHOST_allows_empty813=== PAUSE TestResolveDBConnectionString/PGHOST_allows_empty814=== RUN TestResolveDBConnectionString/nothing_configured815=== PAUSE TestResolveDBConnectionString/nothing_configured816=== CONT TestReadProxyRangeRequest817=== RUN TestUploadHandlersRejectOversizedBody/create_pending_closure818=== PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure819=== RUN TestUploadHandlersRejectOversizedBody/complete_multipart820=== PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart821=== RUN TestUploadHandlersRejectOversizedBody/request_more_parts822=== PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts823=== CONT TestGetExistingObjectsLocksRows8242026/10/06 08:16:03 INFO Aborted multipart uploads count=0 kept=08252026/10/06 08:16:03 WARN Force mode enabled - objects will be deleted immediately without grace period8262026/10/06 08:16:03 INFO Received uploads request method=POST path=/api/pending_closures8272026/10/06 08:16:03 INFO Received uploads request method=POST path=/api/pending_closures8282026/10/06 08:16:03 INFO Received uploads request method=POST path=/api/pending_closures8292026/10/06 08:16:03 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=08302026/10/06 08:16:03 INFO Vacuumed table table=pending_closures8312026/10/06 08:16:03 INFO Vacuumed table table=pending_objects8322026/10/06 08:16:03 INFO Received uploads request method=POST path=/api/pending_closures8332026/10/06 08:16:03 INFO Vacuumed table table=multipart_uploads8342026/10/06 08:16:03 INFO Vacuumed table table=closures8352026/10/06 08:16:03 INFO Vacuumed table table=objects836--- PASS: TestSweepRechecksPendingAfterLocking (0.34s)837=== CONT TestReadRedirectKeepsNarinfoProxied838=== RUN TestPush_RejectsBadRequests/no_objects839=== PAUSE TestPush_RejectsBadRequests/no_objects840=== RUN TestPush_RejectsBadRequests/bad_root841=== PAUSE TestPush_RejectsBadRequests/bad_root842=== RUN TestPush_RejectsBadRequests/root_not_in_objects843=== PAUSE TestPush_RejectsBadRequests/root_not_in_objects844=== RUN TestPush_RejectsBadRequests/no_roots845=== PAUSE TestPush_RejectsBadRequests/no_roots846=== CONT TestRegisterCompletedObjectNeedsPendingRow8472026/10/06 08:16:03 INFO Received uploads request method=POST path=/api/pending_closures848--- PASS: TestService_Rustfstest (0.40s)849=== CONT TestReadRedirectNar8502026/10/06 08:16:03 INFO Registered completed upload object_key=nar/jjjjjjjjjjjjjjjjjjjjjjjjjjjjjj0100000000000000000000.nar.zst8512026/10/06 08:16:03 INFO Received uploads request method=POST path=/api/pending_closures8522026/10/06 08:16:03 INFO Received complete multipart upload request method=POST path=/api/multipart/complete853--- PASS: TestPresignedUploadRegisteredBeforeCommit (0.41s)854=== CONT TestSweepSkipsRowsLockedByPush8552026/10/06 08:16:03 INFO Received uploads request method=POST path=/api/pending_closures8562026/10/06 08:16:04 INFO Received push request method=POST path=/api/pushes8572026/10/06 08:16:04 INFO Received complete push request method=POST path=/api/pushes/1/complete8582026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures8592026/10/06 08:16:04 INFO Received push request method=POST path=/api/pushes8602026/10/06 08:16:04 INFO Received push request method=POST path=/api/pushes8612026/10/06 08:16:04 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign8622026/10/06 08:16:04 INFO Signed narinfos id=1 count=18632026/10/06 08:16:04 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign8642026/10/06 08:16:04 INFO Received complete multipart upload request method=POST path=/api/multipart/complete8652026/10/06 08:16:04 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign8662026/10/06 08:16:04 ERROR Failed to sign narinfos signer="index 1" error="signing key cannot be nil"8672026/10/06 08:16:04 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign8682026/10/06 08:16:04 ERROR Failed to sign narinfos signer=niks3-test-1 error="signer failed"8692026/10/06 08:16:04 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign8702026/10/06 08:16:04 ERROR Signer returned unexpected signature count signer=niks3-test-1 got=0 want=18712026/10/06 08:16:04 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign8722026/10/06 08:16:04 ERROR Signer returned unexpected object key signer=niks3-test-1 object_key=unexpected.narinfo873--- PASS: TestPush_SignsNarinfosOfItsPendingObjects (0.52s)874=== CONT TestReadProxyDisabled8752026/10/06 08:16:04 WARN CompleteMultipartUpload errored but object exists; treating as success error="The specified key does not exist." object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=Y2U3OTllMGItN2E4Zi00Mjc2LThjNWEtYTA1OTRmZjVlMTEyLjcwMzg4YWM4LTQzMDAtNGFkMi1hODUyLTFhMDUzMTIxNDBjYngxNzkxMjc0NTY0MDU4MTYyMTY38762026/10/06 08:16:04 INFO Received cleanup request method=DELETE path=/api/pending_closures8772026/10/06 08:16:04 INFO Aborted multipart uploads count=0 kept=08782026/10/06 08:16:04 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=Y2U3OTllMGItN2E4Zi00Mjc2LThjNWEtYTA1OTRmZjVlMTEyLjcwMzg4YWM4LTQzMDAtNGFkMi1hODUyLTFhMDUzMTIxNDBjYngxNzkxMjc0NTY0MDU4MTYyMTY3 parts=1879--- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (0.53s)880=== CONT TestFailedS3DeleteKeepsTombstone8812026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures8822026/10/06 08:16:04 INFO Aborted multipart uploads count=0 kept=08832026/10/06 08:16:04 WARN Force mode enabled - objects will be deleted immediately without grace period8842026/10/06 08:16:04 INFO Received cleanup request method=DELETE path=/api/pending_closures8852026/10/06 08:16:04 INFO Aborted multipart uploads count=1 kept=08862026/10/06 08:16:04 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=08872026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures8882026/10/06 08:16:04 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete8892026-10-06 08:16:04.187 UTC [473] ERROR: Closure does not exist: id=18902026-10-06 08:16:04.187 UTC [473] CONTEXT: PL/pgSQL function commit_pending_closure(bigint) line 12 at RAISE8912026-10-06 08:16:04.187 UTC [473] STATEMENT: -- name: CommitPendingClosure :exec892 SELECT commit_pending_closure($1::bigint)893 894--- PASS: TestService_cleanupPendingClosuresHandler (0.62s)895=== CONT TestReadProxyRootRedirectsToIndexHTML8962026/10/06 08:16:04 INFO Vacuumed table table=pending_closures8972026/10/06 08:16:04 INFO Vacuumed table table=pending_objects8982026/10/06 08:16:04 INFO Vacuumed table table=multipart_uploads8992026/10/06 08:16:04 INFO Vacuumed table table=closures9002026/10/06 08:16:04 INFO Vacuumed table table=objects9012026/10/06 08:16:04 INFO Received complete push request method=POST path=/api/pushes/2/complete902--- PASS: TestPush_SkippedKeySurvivesGCBeforeCommit (0.63s)903=== CONT TestForceGCDuringPushOffersSweptObject904=== RUN TestForceGCDuringPushOffersSweptObject/before_pending_rows905=== PAUSE TestForceGCDuringPushOffersSweptObject/before_pending_rows906=== RUN TestForceGCDuringPushOffersSweptObject/after_presence_check907=== PAUSE TestForceGCDuringPushOffersSweptObject/after_presence_check908=== CONT TestReadProxyConditionalGet9092026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures9102026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures911--- PASS: TestPresentReportsOnlyClosureRoots (0.68s)912=== CONT TestCreatePendingClosureVerifyS3FailureReleasesConnection9132026/10/06 08:16:04 INFO Received complete multipart upload request method=POST path=/api/multipart/complete9142026/10/06 08:16:04 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"915--- PASS: TestService_AuthMiddleware (0.70s)916=== CONT TestReadProxyOutlastsServerWriteTimeout9172026/10/06 08:16:04 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=Y2U3OTllMGItN2E4Zi00Mjc2LThjNWEtYTA1OTRmZjVlMTEyLjQ2NjI5NWQzLTkxNzktNGE3MC1iY2M3LTU1ZDM2NGE0Njk5NXgxNzkxMjc0NTYzODkyMDYyNDk2 parts=109182026/10/06 08:16:04 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete9192026/10/06 08:16:04 INFO Received push request method=POST path=/api/pushes9202026/10/06 08:16:04 INFO Completed upload id=19212026/10/06 08:16:04 INFO Received get closure request method=GET path=/api/closures/000000000000000000000000000000009222026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures9232026/10/06 08:16:04 INFO Starting cleanup of old closures method=DELETE path=/api/closures9242026/10/06 08:16:04 INFO Aborted multipart uploads count=0 kept=09252026/10/06 08:16:04 INFO Received complete push request method=POST path=/api/pushes/1/complete9262026/10/06 08:16:04 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:16:04 INFO Vacuumed table table=pending_closures9282026/10/06 08:16:04 INFO Vacuumed table table=pending_objects9292026/10/06 08:16:04 INFO Vacuumed table table=multipart_uploads930--- PASS: TestPush_CompleteCommitsEveryRoot (0.75s)931=== CONT TestGCSweepDeliversEachKeyOnce9322026/10/06 08:16:04 INFO Vacuumed table table=closures9332026/10/06 08:16:04 INFO Vacuumed table table=objects9342026/10/06 08:16:04 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000935--- PASS: TestService_createPendingClosureHandler (0.78s)936=== CONT TestReadProxyHead9372026/10/06 08:16:04 INFO Aborted multipart uploads count=0 kept=0938--- PASS: TestReadProxyRangeRequest (0.72s)939=== CONT TestGCAdvisoryLockBlocksConcurrentRun9402026/10/06 08:16:04 WARN Force mode enabled - objects will be deleted immediately without grace period9412026/10/06 08:16:04 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=09422026/10/06 08:16:04 INFO Vacuumed table table=pending_closures9432026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures9442026/10/06 08:16:04 INFO Vacuumed table table=pending_objects9452026/10/06 08:16:04 INFO Vacuumed table table=multipart_uploads9462026/10/06 08:16:04 INFO Vacuumed table table=closures9472026/10/06 08:16:04 INFO Vacuumed table table=objects948--- PASS: TestCommitRacingPendingCleanupKeepsObjects (0.81s)949=== CONT TestReadProxyInvalidPath9502026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures951--- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (0.84s)952=== CONT TestLeadEndsOnShutdown9532026/10/06 08:16:04 INFO Received push request method=POST path=/api/pushes954--- PASS: TestPush_OverlappingRootsStoreOneRowPerKey (0.82s)955=== CONT TestReadProxy4049562026/10/06 08:16:04 INFO Received complete multipart upload request method=POST path=/api/multipart/complete957--- PASS: TestReadRedirectUsesPublicS3URL (0.84s)958=== CONT TestLeadIncumbentAnnouncesAtOnce9592026/10/06 08:16:04 INFO Received complete multipart upload request method=POST path=/api/multipart/complete9602026/10/06 08:16:04 INFO Completed multipart upload object_key=nar/hhhhhhhhhhhhhhhhhhhhhhhhhhhhhh0100000000000000000000.nar.zst upload_id=Y2U3OTllMGItN2E4Zi00Mjc2LThjNWEtYTA1OTRmZjVlMTEyLjEzOTFlODc4LWIzZGYtNGQ4Mi1hYTU2LTU3ZmU3NzBmODAxOXgxNzkxMjc0NTY0MDAzMTcwMzg3 parts=129612026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures9622026/10/06 08:16:04 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst963--- PASS: TestCompleteMultipartUnregistered (0.85s)964=== CONT TestReadProxyNarStreaming965--- PASS: TestCompletedNarNotReofferedAcrossClosures (0.92s)966=== CONT TestLeadElectsOneAndHandsOver967--- PASS: TestGetExistingObjectsLocksRows (0.80s)968=== CONT TestReadProxyNarinfoAlreadyDecompressed969--- PASS: TestReadRedirectKeepsNarinfoProxied (0.64s)970=== CONT TestConnectWaitsForAPeerMigration971--- PASS: TestRegisterCompletedObjectNeedsPendingRow (0.65s)972=== CONT TestReadProxyNarinfo973--- PASS: TestReadRedirectNar (0.63s)974=== CONT TestParseSingleRange975=== RUN TestParseSingleRange/none976=== PAUSE TestParseSingleRange/none977=== RUN TestParseSingleRange/unknown_unit978=== PAUSE TestParseSingleRange/unknown_unit979=== RUN TestParseSingleRange/multi-range_ignored980=== PAUSE TestParseSingleRange/multi-range_ignored981=== RUN TestParseSingleRange/malformed_no_dash982=== PAUSE TestParseSingleRange/malformed_no_dash983=== RUN TestParseSingleRange/malformed_both_empty984=== PAUSE TestParseSingleRange/malformed_both_empty985=== RUN TestParseSingleRange/malformed_end_before_start986=== PAUSE TestParseSingleRange/malformed_end_before_start987=== RUN TestParseSingleRange/closed988=== PAUSE TestParseSingleRange/closed989=== RUN TestParseSingleRange/open-ended990=== PAUSE TestParseSingleRange/open-ended991=== RUN TestParseSingleRange/end_clamped_to_size992=== PAUSE TestParseSingleRange/end_clamped_to_size993=== RUN TestParseSingleRange/suffix994=== PAUSE TestParseSingleRange/suffix995=== RUN TestParseSingleRange/suffix_exceeds_size996=== PAUSE TestParseSingleRange/suffix_exceeds_size997=== RUN TestParseSingleRange/single_byte998=== PAUSE TestParseSingleRange/single_byte999=== RUN TestParseSingleRange/start_past_EOF1000=== PAUSE TestParseSingleRange/start_past_EOF1001=== RUN TestParseSingleRange/start_far_past_EOF1002=== PAUSE TestParseSingleRange/start_far_past_EOF1003=== CONT TestProxyHeadersOnlyTrustedOnSocket10042026/10/06 08:16:04 INFO Received complete multipart upload request method=POST path=/api/multipart/complete10052026/10/06 08:16:04 INFO Aborted multipart uploads count=0 kept=010062026/10/06 08:16:04 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=Y2U3OTllMGItN2E4Zi00Mjc2LThjNWEtYTA1OTRmZjVlMTEyLjBlMWY4ZjdjLWM2NzUtNGFiMi1hZmExLTY5ZmZmMDQ4YzBmOHgxNzkxMjc0NTY0MjE2NDMyODEw parts=1210072026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures1008--- PASS: TestReadProxyDisabled (0.55s)1009=== CONT TestPresentNotReportedWhileGCDeletesClosure10102026/10/06 08:16:04 WARN Force mode enabled - objects will be deleted immediately without grace period10112026/10/06 08:16:04 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=010122026/10/06 08:16:04 INFO Vacuumed table table=pending_closures10132026/10/06 08:16:04 INFO Vacuumed table table=pending_objects10142026/10/06 08:16:04 INFO Vacuumed table table=multipart_uploads10152026/10/06 08:16:04 INFO Vacuumed table table=closures10162026/10/06 08:16:04 INFO Vacuumed table table=objects10172026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures10182026/10/06 08:16:04 INFO Aborted multipart uploads count=0 kept=010192026/10/06 08:16:04 WARN Force mode enabled - objects will be deleted immediately without grace period10202026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures10212026/10/06 08:16:04 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=010222026/10/06 08:16:04 INFO Vacuumed table table=pending_closures10232026/10/06 08:16:04 INFO Vacuumed table table=pending_objects10242026/10/06 08:16:04 INFO Vacuumed table table=multipart_uploads10252026/10/06 08:16:04 INFO Vacuumed table table=closures10262026/10/06 08:16:04 INFO Vacuumed table table=objects1027--- PASS: TestSweepSkipsRowsLockedByPush (0.70s)1028=== CONT TestIsValidCachePath1029=== RUN TestIsValidCachePath/narinfo1030=== PAUSE TestIsValidCachePath/narinfo1031=== RUN TestIsValidCachePath/narinfo_all_nix_base32_chars1032=== PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars1033=== RUN TestIsValidCachePath/nar_zst1034=== PAUSE TestIsValidCachePath/nar_zst1035=== RUN TestIsValidCachePath/nar_xz1036=== PAUSE TestIsValidCachePath/nar_xz1037=== RUN TestIsValidCachePath/nar_bz21038=== PAUSE TestIsValidCachePath/nar_bz21039=== RUN TestIsValidCachePath/nar_uncompressed1040=== PAUSE TestIsValidCachePath/nar_uncompressed1041=== RUN TestIsValidCachePath/ls1042=== PAUSE TestIsValidCachePath/ls1043=== RUN TestIsValidCachePath/log1044=== PAUSE TestIsValidCachePath/log1045=== RUN TestIsValidCachePath/realisation1046=== PAUSE TestIsValidCachePath/realisation1047=== RUN TestIsValidCachePath/nix-cache-info1048=== PAUSE TestIsValidCachePath/nix-cache-info1049=== RUN TestIsValidCachePath/index.html1050=== PAUSE TestIsValidCachePath/index.html1051=== RUN TestIsValidCachePath/traversal_parent1052=== PAUSE TestIsValidCachePath/traversal_parent1053=== RUN TestIsValidCachePath/traversal_in_middle1054=== PAUSE TestIsValidCachePath/traversal_in_middle1055=== RUN TestIsValidCachePath/invalid_char_e1056=== PAUSE TestIsValidCachePath/invalid_char_e1057=== RUN TestIsValidCachePath/invalid_char_u1058=== PAUSE TestIsValidCachePath/invalid_char_u1059=== RUN TestIsValidCachePath/random_path1060=== PAUSE TestIsValidCachePath/random_path1061=== RUN TestIsValidCachePath/empty1062=== PAUSE TestIsValidCachePath/empty1063=== RUN TestIsValidCachePath/leading_slash1064=== PAUSE TestIsValidCachePath/leading_slash1065=== RUN TestIsValidCachePath/wrong_extension1066=== PAUSE TestIsValidCachePath/wrong_extension1067=== RUN TestIsValidCachePath/short_hash1068=== PAUSE TestIsValidCachePath/short_hash1069=== CONT TestGCBugBareHashReferences1070--- PASS: TestPushWaitsForSweepHoldingRow (1.11s)1071=== CONT TestClientErrorHandling1072=== RUN TestClientErrorHandling/InvalidStorePath1073=== PAUSE TestClientErrorHandling/InvalidStorePath1074=== RUN TestClientErrorHandling/InvalidAuthToken1075=== PAUSE TestClientErrorHandling/InvalidAuthToken1076=== RUN TestClientErrorHandling/ServerNotAvailable1077=== PAUSE TestClientErrorHandling/ServerNotAvailable1078=== CONT TestNARDeduplicationMetadataUploadBug1079--- PASS: TestReadProxyRootRedirectsToIndexHTML (0.51s)1080=== CONT TestTombstonedObjectOfferedWithoutWaiting10812026/10/06 08:16:04 INFO Received complete multipart upload request method=POST path=/api/multipart/complete10822026/10/06 08:16:04 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=Y2U3OTllMGItN2E4Zi00Mjc2LThjNWEtYTA1OTRmZjVlMTEyLjI3ZDEyNTNiLWE5ZmQtNDBjMy05YmMzLTVlNWM0N2FjNjdlYngxNzkxMjc0NTY0Mzc5ODUwNzU4 parts=1010832026/10/06 08:16:04 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1084--- PASS: TestReadProxyConditionalGet (0.53s)1085=== CONT TestGCSweepSkipsPendingObjects10862026/10/06 08:16:04 INFO Completed upload id=110872026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures10882026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures10892026/10/06 08:16:04 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo10902026/10/06 08:16:04 WARN Found objects in DB but missing from S3, will re-upload count=11091--- PASS: TestService_verifyS3Integrity (1.17s)1092=== CONT TestCreatePin_ReservedPins10932026/10/06 08:16:04 INFO Aborted multipart uploads count=0 kept=010942026/10/06 08:16:04 INFO Received uploads request method=POST path=/api/pending_closures10952026/10/06 08:16:04 WARN Force mode enabled - objects will be deleted immediately without grace period10962026/10/06 08:16:04 ERROR failed to remove object object=nar/dddddddddddddddddddddddddddddddddddddddddddddddddddd.nar.zst error="Access Denied."10972026/10/06 08:16:04 ERROR failed to remove object object=dddddddddddddddddddddddddddddddd.narinfo error="Access Denied."1098--- PASS: TestFailedS3DeleteKeepsTombstone (0.67s)1099=== CONT TestDeduplicatedObjectsRecordedAsPending11002026/10/06 08:16:04 INFO Aborted multipart uploads count=0 kept=011012026/10/06 08:16:04 WARN Force mode enabled - objects will be deleted immediately without grace period11022026/10/06 08:16:04 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"1103--- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.48s)1104=== CONT TestOrphanedObjectsGCStressTest11052026/10/06 08:16:04 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=011062026/10/06 08:16:04 INFO Vacuumed table table=pending_closures11072026/10/06 08:16:04 INFO Vacuumed table table=pending_objects11082026/10/06 08:16:04 INFO Vacuumed table table=multipart_uploads11092026/10/06 08:16:04 INFO Vacuumed table table=closures11102026/10/06 08:16:04 INFO Vacuumed table table=objects1111--- PASS: TestGCSweepDeliversEachKeyOnce (0.53s)1112=== CONT TestConcurrentCommitsSharingObjectsDoNotDeadlock1113--- PASS: TestReadProxyHead (0.50s)1114=== CONT TestPushDedupSurvivesConcurrentGC11152026/10/06 08:16:04 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:40835/oidc1116--- PASS: TestReadProxyInvalidPath (0.49s)1117=== CONT TestOrphanedObjectsGC11182026/10/06 08:16:04 INFO lead: acquired remote=192.0.2.1:123411192026/10/06 08:16:04 INFO lead: acquired remote=192.0.2.1:123411202026/10/06 08:16:04 INFO lead: released remote=192.0.2.1:12341121--- PASS: TestLeadIncumbentAnnouncesAtOnce (0.48s)1122=== CONT TestClientFallsBackToClosures1123--- PASS: TestReadProxy404 (0.51s)1124=== CONT TestGCMetrics1125--- PASS: TestCreatePendingClosureVerifyS3FailureReleasesConnection (0.71s)1126=== CONT TestObjectStatsTrigger1127--- PASS: TestReadProxyNarStreaming (0.50s)1128=== CONT TestClientPushesUseOnePush11292026/10/06 08:16:05 INFO lead: acquired remote=192.0.2.1:12341130--- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.52s)1131=== CONT TestPendingCleanupUsesOneCutoff11322026/10/06 08:16:05 INFO Received complete multipart upload request method=POST path=/api/multipart/complete11332026/10/06 08:16:05 WARN Failed to abort redundant multipart upload, keeping its row object_key=nar/gggggggggggggggggggggggggggggg0200000000000000000000.nar.zst upload_id=Y2U3OTllMGItN2E4Zi00Mjc2LThjNWEtYTA1OTRmZjVlMTEyLmZlMDVkMTA2LWQyNTgtNDFhNi1iMmQ5LTZkMTdkYmI2Y2QwZngxNzkxMjc0NTY0NjU4NDQyMzcz error="Get \"http://127.0.0.1:1/bucket15/?location=\": dial tcp 127.0.0.1:1: connect: connection refused"11342026/10/06 08:16:05 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0200000000000000000000.nar.zst upload_id=Y2U3OTllMGItN2E4Zi00Mjc2LThjNWEtYTA1OTRmZjVlMTEyLjVjYTBhMTcxLTY0MGQtNDI5MC05M2RlLTA1YTlmZWEzZWViMHgxNzkxMjc0NTY0NjQ1NzYzOTgy parts=1211352026/10/06 08:16:05 INFO Received cleanup request method=DELETE path=/api/pending_closures11362026/10/06 08:16:05 INFO Aborted multipart uploads count=1 kept=01137--- PASS: TestRedundantMultipartUpload (1.52s)1138=== CONT TestPinProtectsFromGC11392026/10/06 08:16:05 INFO Starting HTTP server address=/build/TestProxyHeadersOnlyTrustedOnSocket2991183978/001/proxy.sock11402026/10/06 08:16:05 INFO Starting HTTP server address=127.0.0.1:3858111412026/10/06 08:16:05 WARN mTLS auth: subject not in bound subjects subject="CN=someone"11422026/10/06 08:16:05 INFO Shutdown signal received, draining in-flight requests timeout=10s1143--- PASS: TestProxyHeadersOnlyTrustedOnSocket (0.49s)1144=== CONT TestMultipartUploadAbortedWhenCancelledBeforeRecorded11452026/10/06 08:16:05 ERROR Failed to decompress narinfo error="decompressed size exceeds configured limit"1146=== NAME TestNARDeduplicationMetadataUploadBug1147 metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug1032258654/001/store/7k93m1r2sg9m40qahjwyhnp8i5yzd5nd-file1.txt11482026/10/06 08:16:05 INFO Received uploads request method=POST path=/api/pending_closures1149--- PASS: TestTombstonedObjectOfferedWithoutWaiting (0.51s)1150=== CONT TestClientSharedPathCommittedMidPush11512026/10/06 08:16:05 INFO Received uploads request method=POST path=/api/pending_closures11522026/10/06 08:16:05 INFO Aborted multipart uploads count=0 kept=011532026/10/06 08:16:05 INFO Received uploads request method=POST path=/api/pending_closures11542026/10/06 08:16:05 WARN Force mode enabled - objects will be deleted immediately without grace period11552026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes11562026/10/06 08:16:05 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=011572026/10/06 08:16:05 ERROR Refusing narinfo larger than the limit key=5hcdxyjf9yiq7qf3i4548drb6sjmwa1v.narinfo limit=1677721611582026/10/06 08:16:05 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)11592026/10/06 08:16:05 INFO Uploading 7k93m1r2sg9m40qahjwyhnp8i5yzd5nd-file1.txt (160B)1160--- PASS: TestReadProxyNarinfo (0.70s)1161=== CONT TestMultipartCleanup11622026/10/06 08:16:05 INFO Vacuumed table table=pending_closures1163--- PASS: TestDeduplicatedObjectsRecordedAsPending (0.51s)1164=== CONT TestClientWithDependencies11652026/10/06 08:16:05 INFO Vacuumed table table=pending_objects11662026/10/06 08:16:05 INFO Vacuumed table table=multipart_uploads11672026/10/06 08:16:05 INFO Vacuumed table table=closures11682026/10/06 08:16:05 INFO Vacuumed table table=objects11692026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst error="server returned 404: 404 page not found\n"11702026/10/06 08:16:05 WARN Failed to register uploaded object key=7k93m1r2sg9m40qahjwyhnp8i5yzd5nd.ls error="server returned 404: 404 page not found\n"11712026/10/06 08:16:05 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign11722026/10/06 08:16:05 INFO Signed narinfos id=1 count=111732026/10/06 08:16:05 INFO Uploading 1 narinfos11742026/10/06 08:16:05 INFO Received complete push request method=POST path=/api/pushes/1/complete11752026/10/06 08:16:05 WARN Failed to register uploaded object key=7k93m1r2sg9m40qahjwyhnp8i5yzd5nd.narinfo error="server returned 404: 404 page not found\n"1176--- PASS: TestGCSweepSkipsPendingObjects (0.55s)1177=== CONT TestServerTLSConfig1178=== RUN TestServerTLSConfig/no_client_CA1179=== PAUSE TestServerTLSConfig/no_client_CA1180=== RUN TestServerTLSConfig/missing_CA_file1181=== PAUSE TestServerTLSConfig/missing_CA_file1182=== RUN TestServerTLSConfig/not_a_PEM_file1183=== PAUSE TestServerTLSConfig/not_a_PEM_file1184=== CONT TestService_RequireScope_OIDC11852026/10/06 08:16:05 INFO Upload complete. (61ms)1186=== NAME TestNARDeduplicationMetadataUploadBug1187 metadata_upload_test.go:54: Retrieved narinfo from S3:1188 StorePath: /build/TestNARDeduplicationMetadataUploadBug1032258654/001/store/7k93m1r2sg9m40qahjwyhnp8i5yzd5nd-file1.txt1189 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1190 Compression: zstd1191 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1192 NarSize: 1601193 References: 1194 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1195 metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)1196 metadata_upload_test.go:55: Decompressed .ls content (64 bytes):1197 {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}1198 metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug1032258654/001/store/dgbzavx12lb03v4fhchwhf309ddi1f4l-file2.txt11992026/10/06 08:16:05 INFO Received uploads request method=POST path=/api/pending_closures12002026/10/06 08:16:05 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux12012026/10/06 08:16:05 WARN Refused reserved pin name=worker-x86_64-linux12022026/10/06 08:16:05 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux12032026/10/06 08:16:05 INFO Received create pin request method=POST path=/api/pins/my-app12042026/10/06 08:16:05 INFO Received create pin request method=POST path=/api/pins/worker-x86_64-linux1205--- PASS: TestCreatePin_ReservedPins (0.61s)1206=== CONT TestService_NativeMTLS1207--- PASS: TestGCBugBareHashReferences (0.72s)1208=== CONT TestClientMultipleUploads12092026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes12102026/10/06 08:16:05 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)12112026/10/06 08:16:05 INFO Aborted multipart uploads count=0 kept=012122026/10/06 08:16:05 INFO Aborted multipart uploads count=0 kept=012132026/10/06 08:16:05 WARN Failed to register uploaded object key=dgbzavx12lb03v4fhchwhf309ddi1f4l.ls error="server returned 404: 404 page not found\n"12142026/10/06 08:16:05 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign12152026/10/06 08:16:05 INFO Signed narinfos id=2 count=112162026/10/06 08:16:05 INFO Uploading 1 narinfos12172026/10/06 08:16:05 WARN Force mode enabled - objects will be deleted immediately without grace period12182026/10/06 08:16:05 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=012192026/10/06 08:16:05 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=012202026/10/06 08:16:05 INFO Received complete push request method=POST path=/api/pushes/2/complete12212026/10/06 08:16:05 WARN Failed to register uploaded object key=dgbzavx12lb03v4fhchwhf309ddi1f4l.narinfo error="server returned 404: 404 page not found\n"12222026/10/06 08:16:05 INFO Vacuumed table table=pending_closures12232026/10/06 08:16:05 INFO Vacuumed table table=pending_objects12242026/10/06 08:16:05 INFO Vacuumed table table=multipart_uploads12252026/10/06 08:16:05 INFO Vacuumed table table=closures12262026/10/06 08:16:05 INFO Vacuumed table table=objects12272026/10/06 08:16:05 INFO Vacuumed table table=pending_closures12282026/10/06 08:16:05 INFO Upload complete. (50ms)12292026/10/06 08:16:05 INFO Vacuumed table table=pending_objects1230=== NAME TestNARDeduplicationMetadataUploadBug1231 metadata_upload_test.go:76: Retrieved narinfo from S3:1232 StorePath: /build/TestNARDeduplicationMetadataUploadBug1032258654/001/store/dgbzavx12lb03v4fhchwhf309ddi1f4l-file2.txt1233 URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst1234 Compression: zstd1235 NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf1236 NarSize: 1601237 References: 1238 CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf12392026/10/06 08:16:05 INFO Vacuumed table table=multipart_uploads1240--- PASS: TestGCMetrics (0.47s)1241=== CONT TestMetricsInventory1242=== NAME TestNARDeduplicationMetadataUploadBug1243 metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)1244 metadata_upload_test.go:77: Decompressed .ls content (49 bytes):1245 {"version":1,"root":{"type":"regular","size":44}}12462026/10/06 08:16:05 INFO Vacuumed table table=closures12472026/10/06 08:16:05 INFO Vacuumed table table=objects1248--- PASS: TestNARDeduplicationMetadataUploadBug (0.75s)1249=== CONT TestCacheConfigHandler1250=== RUN TestCacheConfigHandler/full_config,_no_issuer1251=== PAUSE TestCacheConfigHandler/full_config,_no_issuer1252=== RUN TestCacheConfigHandler/no_cache_url_configured1253=== PAUSE TestCacheConfigHandler/no_cache_url_configured1254=== RUN TestCacheConfigHandler/no_signing_keys1255=== PAUSE TestCacheConfigHandler/no_signing_keys1256=== RUN TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1257=== PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1258=== CONT TestClientIntegration12592026/10/06 08:16:05 INFO Aborted multipart uploads count=0 kept=012602026/10/06 08:16:05 WARN Force mode enabled - objects will be deleted immediately without grace period12612026/10/06 08:16:05 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=012622026/10/06 08:16:05 INFO Vacuumed table table=pending_closures1263--- PASS: TestObjectStatsTrigger (0.48s)1264=== CONT TestService_ReadScope_PublicByDefault12652026/10/06 08:16:05 INFO Vacuumed table table=pending_objects12662026/10/06 08:16:05 INFO Vacuumed table table=multipart_uploads12672026/10/06 08:16:05 INFO Vacuumed table table=closures12682026/10/06 08:16:05 INFO Vacuumed table table=objects1269--- PASS: TestPushDedupSurvivesConcurrentGC (0.60s)1270=== CONT TestService_healthCheckHandler12712026/10/06 08:16:05 INFO Received uploads request method=POST path=/api/pending_closures12722026/10/06 08:16:05 INFO Received uploads request method=POST path=/api/pending_closures12732026/10/06 08:16:05 INFO Received cleanup request method=DELETE path=/api/pending_closures12742026/10/06 08:16:05 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:37197/oidc12752026/10/06 08:16:05 INFO Received uploads request method=POST path=/api/pending_closures1276--- PASS: TestMultipartUploadAbortedWhenCancelledBeforeRecorded (0.47s)1277=== CONT TestGCTaskStore_PhaseUpdates1278--- PASS: TestGCTaskStore_PhaseUpdates (0.00s)1279=== CONT TestCacheStatsHandler12802026/10/06 08:16:05 INFO Received uploads request method=POST path=/api/pending_closures12812026/10/06 08:16:05 INFO Received uploads request method=POST path=/api/pending_closures12822026/10/06 08:16:05 INFO Uploading 2 paths to 127.0.0.1 (1 already cached)12832026/10/06 08:16:05 INFO Uploading b6hpflhni2hi0y1kgrc1h3v3gl7rcd99-a (216B)12842026/10/06 08:16:05 INFO Uploading 1is31mg4x6sdqabcmmpysv66dak58k8l-shared-dep (136B)1285=== NAME TestPinProtectsFromGC1286 client_integration_test.go:731: Pinned store path: /build/TestPinProtectsFromGC2428553955/001/store/02316i01sz2q923vcw125wvigag9iry9-pinned-file.txt1287 client_integration_test.go:732: Unpinned store path: /build/TestPinProtectsFromGC2428553955/001/store/wpn760pi482hcvi07742wcma585r324v-unpinned-file.txt12882026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"12892026/10/06 08:16:05 WARN Failed to register uploaded object key=b6hpflhni2hi0y1kgrc1h3v3gl7rcd99.ls error="server returned 404: 404 page not found\n"12902026/10/06 08:16:05 WARN Failed to register uploaded object key=1bcia1f46b2jc8kywccyszwwwbkrfcvj.ls error="server returned 404: 404 page not found\n"12912026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/1x9lhbjpqja06ma6i5g02nng1cv3bf6m2nnikz7vqs68ir532awa.nar.zst error="server returned 404: 404 page not found\n"12922026/10/06 08:16:05 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign12932026/10/06 08:16:05 WARN Failed to register uploaded object key=1is31mg4x6sdqabcmmpysv66dak58k8l.ls error="server returned 404: 404 page not found\n"12942026/10/06 08:16:05 INFO Signed narinfos id=1 count=212952026/10/06 08:16:05 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign12962026/10/06 08:16:05 INFO Signed narinfos id=2 count=212972026/10/06 08:16:05 INFO Uploading 4 narinfos12982026/10/06 08:16:05 WARN Failed to register uploaded object key=1bcia1f46b2jc8kywccyszwwwbkrfcvj.narinfo error="server returned 404: 404 page not found\n"12992026/10/06 08:16:05 WARN Failed to register uploaded object key=1is31mg4x6sdqabcmmpysv66dak58k8l.narinfo error="server returned 404: 404 page not found\n"13002026/10/06 08:16:05 WARN Failed to register uploaded object key=b6hpflhni2hi0y1kgrc1h3v3gl7rcd99.narinfo error="server returned 404: 404 page not found\n"13012026/10/06 08:16:05 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete13022026/10/06 08:16:05 WARN Failed to register uploaded object key=1is31mg4x6sdqabcmmpysv66dak58k8l.narinfo error="server returned 404: 404 page not found\n"13032026/10/06 08:16:05 INFO Completed upload id=113042026/10/06 08:16:05 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete13052026/10/06 08:16:05 INFO Completed upload id=213062026/10/06 08:16:05 INFO Upload complete. (77ms)1307=== NAME TestClientFallsBackToClosures13082026/10/06 08:16:05 INFO Received uploads request method=POST path=/api/pending_closures1309 client_pushes_test.go:112: Retrieved narinfo from S3:1310 StorePath: /build/TestClientFallsBackToClosures2725162011/001/store/1is31mg4x6sdqabcmmpysv66dak58k8l-shared-dep1311 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1312 Compression: zstd1313 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821314 NarSize: 1361315 References: 1316 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1317 client_pushes_test.go:112: Retrieved narinfo from S3:1318 StorePath: /build/TestClientFallsBackToClosures2725162011/001/store/b6hpflhni2hi0y1kgrc1h3v3gl7rcd99-a1319 URL: nar/1x9lhbjpqja06ma6i5g02nng1cv3bf6m2nnikz7vqs68ir532awa.nar.zst1320 Compression: zstd1321 NarHash: sha256:1x9lhbjpqja06ma6i5g02nng1cv3bf6m2nnikz7vqs68ir532awa1322 NarSize: 2161323 References: /build/TestClientFallsBackToClosures2725162011/001/store/1is31mg4x6sdqabcmmpysv66dak58k8l-shared-dep1324 CA: text:sha256:10bi7q3x45yd45mg883hvqifwwp7n3cx7fpmk4s875b3jyp0sh551325 client_pushes_test.go:112: Retrieved narinfo from S3:1326 StorePath: /build/TestClientFallsBackToClosures2725162011/001/store/1bcia1f46b2jc8kywccyszwwwbkrfcvj-b1327 URL: nar/1x9lhbjpqja06ma6i5g02nng1cv3bf6m2nnikz7vqs68ir532awa.nar.zst1328 Compression: zstd1329 NarHash: sha256:1x9lhbjpqja06ma6i5g02nng1cv3bf6m2nnikz7vqs68ir532awa1330 NarSize: 2161331 References: /build/TestClientFallsBackToClosures2725162011/001/store/1is31mg4x6sdqabcmmpysv66dak58k8l-shared-dep1332 CA: text:sha256:10bi7q3x45yd45mg883hvqifwwp7n3cx7fpmk4s875b3jyp0sh551333--- PASS: TestPresentNotReportedWhileGCDeletesClosure (0.98s)1334=== CONT TestGracefulShutdownDrainsInflight13352026/10/06 08:16:05 INFO Starting HTTP server address=127.0.0.1:3591513362026/10/06 08:16:05 INFO Shutdown signal received, draining in-flight requests timeout=10s1337--- PASS: TestClientFallsBackToClosures (0.67s)1338=== CONT TestGCTaskStore_CompletedAllowsNewTask1339--- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)1340=== CONT TestGCTaskStore_Fail1341--- PASS: TestGCTaskStore_Fail (0.00s)1342=== CONT TestClientCADerivations13432026/10/06 08:16:05 WARN mTLS auth: subject not in bound subjects subject="CN=reader"13442026/10/06 08:16:05 WARN mTLS auth: subject not in bound subjects subject="CN=reader"1345--- PASS: TestService_NativeMTLS (0.28s)1346=== CONT TestGenerateLandingPage1347--- PASS: TestGenerateLandingPage (0.00s)1348=== CONT TestService_ReadAuthMiddleware13492026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes13502026/10/06 08:16:05 INFO Uploading 2 paths to 127.0.0.1 (1 already cached)13512026/10/06 08:16:05 INFO Uploading f5x0yhzzji06srafmqps258y8w5ys175-shared-dep (136B)13522026/10/06 08:16:05 INFO Uploading mrv5zhd8x24ipa4ssp7h53hrlwkv3pbd-b (216B)13532026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/18sxbvmncxvhixsizj21dbabksdl512kxly45nwhm74w0vb0ilmg.nar.zst error="server returned 404: 404 page not found\n"13542026/10/06 08:16:05 WARN Failed to register uploaded object key=f5x0yhzzji06srafmqps258y8w5ys175.ls error="server returned 404: 404 page not found\n"13552026/10/06 08:16:05 WARN Failed to register uploaded object key=mrv5zhd8x24ipa4ssp7h53hrlwkv3pbd.ls error="server returned 404: 404 page not found\n"13562026/10/06 08:16:05 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign13572026/10/06 08:16:05 WARN Failed to register uploaded object key=3iaj7bhkg05f4xm70mlash3gc6nn00hn.ls error="server returned 404: 404 page not found\n"13582026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"13592026/10/06 08:16:05 INFO Signed narinfos id=1 count=313602026/10/06 08:16:05 INFO Uploading 3 narinfos13612026/10/06 08:16:05 WARN Failed to register uploaded object key=3iaj7bhkg05f4xm70mlash3gc6nn00hn.narinfo error="server returned 404: 404 page not found\n"13622026/10/06 08:16:05 WARN Failed to register uploaded object key=mrv5zhd8x24ipa4ssp7h53hrlwkv3pbd.narinfo error="server returned 404: 404 page not found\n"13632026/10/06 08:16:05 INFO Received complete push request method=POST path=/api/pushes/1/complete13642026/10/06 08:16:05 WARN Failed to register uploaded object key=f5x0yhzzji06srafmqps258y8w5ys175.narinfo error="server returned 404: 404 page not found\n"13652026/10/06 08:16:05 INFO Upload complete. (63ms)1366=== NAME TestClientPushesUseOnePush1367 client_pushes_test.go:97: Retrieved narinfo from S3:1368 StorePath: /build/TestClientPushesUseOnePush2328059209/001/store/f5x0yhzzji06srafmqps258y8w5ys175-shared-dep1369 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1370 Compression: zstd1371 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821372 NarSize: 1361373 References: 1374 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n13752026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes1376 client_pushes_test.go:97: Retrieved narinfo from S3:1377 StorePath: /build/TestClientPushesUseOnePush2328059209/001/store/3iaj7bhkg05f4xm70mlash3gc6nn00hn-a1378 URL: nar/18sxbvmncxvhixsizj21dbabksdl512kxly45nwhm74w0vb0ilmg.nar.zst1379 Compression: zstd1380 NarHash: sha256:18sxbvmncxvhixsizj21dbabksdl512kxly45nwhm74w0vb0ilmg1381 NarSize: 2161382 References: /build/TestClientPushesUseOnePush2328059209/001/store/f5x0yhzzji06srafmqps258y8w5ys175-shared-dep1383 CA: text:sha256:13db6mq7hp3vva542abxrbm0gwf6mchjmj456w251hb36qb1y85q1384 client_pushes_test.go:97: Retrieved narinfo from S3:1385 StorePath: /build/TestClientPushesUseOnePush2328059209/001/store/mrv5zhd8x24ipa4ssp7h53hrlwkv3pbd-b1386 URL: nar/18sxbvmncxvhixsizj21dbabksdl512kxly45nwhm74w0vb0ilmg.nar.zst1387 Compression: zstd1388 NarHash: sha256:18sxbvmncxvhixsizj21dbabksdl512kxly45nwhm74w0vb0ilmg1389 NarSize: 2161390 References: /build/TestClientPushesUseOnePush2328059209/001/store/f5x0yhzzji06srafmqps258y8w5ys175-shared-dep1391 CA: text:sha256:13db6mq7hp3vva542abxrbm0gwf6mchjmj456w251hb36qb1y85q13922026/10/06 08:16:05 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)13932026/10/06 08:16:05 INFO Uploading 02316i01sz2q923vcw125wvigag9iry9-pinned-file.txt (128B)1394=== NAME TestClientWithDependencies1395 client_integration_test.go:613: Built derivation: /build/TestClientWithDependencies1253209235/001/store/kq6sdrg0kbc1zwq7zkdy3vnw1lw80xnx-test-script1396--- PASS: TestClientPushesUseOnePush (0.69s)1397=== CONT TestCacheConfigHandlerMaxNarSize1398--- PASS: TestCacheConfigHandlerMaxNarSize (0.00s)1399=== CONT TestService_AuthMiddleware_OIDC14002026/10/06 08:16:05 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign14012026/10/06 08:16:05 WARN Failed to register uploaded object key=02316i01sz2q923vcw125wvigag9iry9.ls error="server returned 404: 404 page not found\n"14022026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/0qmhsg42pqsz7x6hh02j8gznnac3yi3qpjhrqbwvz88i194jbb5s.nar.zst error="server returned 404: 404 page not found\n"14032026/10/06 08:16:05 INFO Signed narinfos id=1 count=114042026/10/06 08:16:05 INFO Uploading 1 narinfos14052026/10/06 08:16:05 INFO Received complete push request method=POST path=/api/pushes/1/complete14062026/10/06 08:16:05 WARN Failed to register uploaded object key=02316i01sz2q923vcw125wvigag9iry9.narinfo error="server returned 404: 404 page not found\n"1407--- PASS: TestGracefulShutdownDrainsInflight (0.07s)1408=== CONT TestGCTaskStore_GetReturnsLatest1409--- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)1410=== CONT TestService_AuthMiddleware_MTLSBoundSubjects14112026/10/06 08:16:05 INFO Upload complete. (74ms)1412=== NAME TestClientMultipleUploads1413 client_integration_test.go:358: Created store path 0: /build/TestClientMultipleUploads4182213582/001/store/sp8021z5lp9m36lzpnx8s0736nf2pg3r-test-file-0.txt1414=== NAME TestClientWithDependencies1415 client_integration_test.go:615: Found 1 dependencies (including self)1416=== NAME TestOrphanedObjectsGC1417 orphaned_objects_gc_test.go:290: GC Test Summary:1418 orphaned_objects_gc_test.go:291: - Kept: 2 objects from closure A1419 orphaned_objects_gc_test.go:292: - Deleted: 2 objects from closure B1420 orphaned_objects_gc_test.go:293: - Deleted: 6 orphaned chain objects (X1->X2->X3)1421 orphaned_objects_gc_test.go:294: - Deleted: 2 orphaned single objects (Y)1422 orphaned_objects_gc_test.go:295: - Total deleted: 10 objects1423--- PASS: TestOrphanedObjectsGC (0.84s)1424=== CONT TestService_AuthMiddleware_MTLSProxyHeader14252026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes14262026/10/06 08:16:05 INFO Uploading 2 paths to 127.0.0.1 (0 already cached)1427--- PASS: TestConcurrentCommitsSharingObjectsDoNotDeadlock (0.86s)14282026/10/06 08:16:05 INFO Uploading lid40729x53c5lrgldwzq6aqkz5g81yf-top (224B)1429=== CONT TestCreatePendingClosureRejectsOversizedNAR14302026/10/06 08:16:05 INFO Uploading zlid2fb3j7nsjy6ncvcq8q1cpswdzsa7-shared-dep (136B)14312026/10/06 08:16:05 INFO Received uploads request method=POST path=/api/pending_closures1432--- PASS: TestCreatePendingClosureRejectsOversizedNAR (0.00s)1433=== CONT TestService_readinessHandler14342026/10/06 08:16:05 INFO Received cleanup request method=DELETE path=/api/pending_closures14352026/10/06 08:16:05 WARN Failed to abort upload, keeping its closure key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst error="Get \"http://127.0.0.1:1/bucket69/?location=\": dial tcp 127.0.0.1:1: connect: connection refused" code=""14362026/10/06 08:16:05 INFO Aborted multipart uploads count=0 kept=114372026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst error="server returned 404: 404 page not found\n"14382026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/0baixih7qlycd1278jyffj13hygfv5vxq0d050lw75r81nc7nvdx.nar.zst error="server returned 404: 404 page not found\n"14392026/10/06 08:16:05 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign14402026/10/06 08:16:05 WARN Failed to register uploaded object key=zlid2fb3j7nsjy6ncvcq8q1cpswdzsa7.ls error="server returned 404: 404 page not found\n"14412026/10/06 08:16:05 WARN Failed to register uploaded object key=lid40729x53c5lrgldwzq6aqkz5g81yf.ls error="server returned 404: 404 page not found\n"14422026/10/06 08:16:05 INFO Signed narinfos id=1 count=214432026/10/06 08:16:05 INFO Uploading 2 narinfos14442026/10/06 08:16:05 INFO Received cleanup request method=DELETE path=/api/pending_closures1445--- PASS: TestMetricsInventory (0.31s)1446=== CONT TestGCEndsOnShutdown/before_the_run14472026/10/06 08:16:05 INFO Received complete push request method=POST path=/api/pushes/1/complete14482026/10/06 08:16:05 WARN Failed to register uploaded object key=zlid2fb3j7nsjy6ncvcq8q1cpswdzsa7.narinfo error="server returned 404: 404 page not found\n"14492026/10/06 08:16:05 WARN Failed to register uploaded object key=lid40729x53c5lrgldwzq6aqkz5g81yf.narinfo error="server returned 404: 404 page not found\n"14502026/10/06 08:16:05 INFO Aborted multipart uploads count=1 kept=01451--- PASS: TestMultipartCleanup (0.47s)1452=== CONT TestPendingClosureWriteTimeout/empty1453=== CONT TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark14542026/10/06 08:16:05 INFO Upload complete. (70ms)1455=== NAME TestClientMultipleUploads1456 client_integration_test.go:358: Created store path 1: /build/TestClientMultipleUploads4182213582/001/store/0p25x86q9kxnifhjs7dk2gf2xxi14a4y-test-file-1.txt1457=== NAME TestClientSharedPathCommittedMidPush1458 client_integration_test.go:680: Retrieved narinfo from S3:1459 StorePath: /build/TestClientSharedPathCommittedMidPush3149436889/001/store/zlid2fb3j7nsjy6ncvcq8q1cpswdzsa7-shared-dep1460 URL: nar/1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y82.nar.zst1461 Compression: zstd1462 NarHash: sha256:1vnl0g6w4zgpfz06rb321mfd3jqsrs120jcl8dg2dxn014875y821463 NarSize: 1361464 References: 1465 CA: text:sha256:08nxw05k7b0pzzy0f3swvnw4ldxdbclkdnklpidwmrxdhvfrmv7n1466 client_integration_test.go:680: Retrieved narinfo from S3:1467 StorePath: /build/TestClientSharedPathCommittedMidPush3149436889/001/store/lid40729x53c5lrgldwzq6aqkz5g81yf-top1468 URL: nar/0baixih7qlycd1278jyffj13hygfv5vxq0d050lw75r81nc7nvdx.nar.zst1469 Compression: zstd1470 NarHash: sha256:0baixih7qlycd1278jyffj13hygfv5vxq0d050lw75r81nc7nvdx1471 NarSize: 2241472 References: /build/TestClientSharedPathCommittedMidPush3149436889/001/store/zlid2fb3j7nsjy6ncvcq8q1cpswdzsa7-shared-dep1473 CA: text:sha256:0kh3z6ja6bmavpbqx3kav6srdqnpaw1y0syijpcdxw3hzn6j7ln41474--- PASS: TestClientSharedPathCommittedMidPush (0.55s)1475=== CONT TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete1476--- PASS: TestService_ReadScope_PublicByDefault (0.33s)1477=== CONT TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline14782026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes14792026/10/06 08:16:05 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:35483/oidc1480=== NAME TestClientMultipleUploads1481 client_integration_test.go:358: Created store path 2: /build/TestClientMultipleUploads4182213582/001/store/k5px2d8zq0qgmbicpgssjvxf48c3f0mc-test-file-2.txt14822026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes14832026/10/06 08:16:05 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14842026/10/06 08:16:05 INFO Uploading wpn760pi482hcvi07742wcma585r324v-unpinned-file.txt (128B)14852026/10/06 08:16:05 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)14862026/10/06 08:16:05 INFO Uploading kq6sdrg0kbc1zwq7zkdy3vnw1lw80xnx-test-script (136B)14872026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/0gh0wdnr6gmk4r5sfzzh12isnl14rgmkrwy1ws41hpsrlndclg5h.nar.zst error="server returned 404: 404 page not found\n"14882026/10/06 08:16:05 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign1489--- PASS: TestService_healthCheckHandler (0.34s)14902026/10/06 08:16:05 WARN Failed to register uploaded object key=wpn760pi482hcvi07742wcma585r324v.ls error="server returned 404: 404 page not found\n"1491=== CONT TestPendingClosureWriteTimeout/670k_objects1492=== CONT TestPendingClosureWriteTimeout/400_objects1493=== CONT TestProxyWriteTimeout/narinfo14942026/10/06 08:16:05 INFO Signed narinfos id=2 count=11495=== CONT TestProxyWriteTimeout/unknown_size1496=== CONT TestProxyWriteTimeout/10_GiB_nar1497=== CONT TestProxyWriteTimeout/1_GiB_nar1498--- PASS: TestProxyWriteTimeout (0.06s)1499 --- PASS: TestProxyWriteTimeout/narinfo (0.00s)1500 --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)1501 --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)1502 --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)1503=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info15042026/10/06 08:16:05 INFO Uploading 1 narinfos15052026/10/06 08:16:05 INFO Received uploads request method=POST path=/1506=== CONT TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key15072026/10/06 08:16:05 INFO Received request for more parts method=POST path=/1508=== CONT TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key15092026/10/06 08:16:05 INFO Received complete multipart upload request method=POST path=/1510=== CONT TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal15112026/10/06 08:16:05 INFO Received uploads request method=POST path=/1512--- PASS: TestUploadHandlersRejectInvalidKeys (0.06s)1513 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)1514 --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)1515 --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)1516 --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)1517=== CONT TestIsValidUploadKey/narinfo1518=== CONT TestIsValidUploadKey/unknown_type1519=== CONT TestIsValidUploadKey/empty_key1520=== CONT TestIsValidUploadKey/absolute1521=== CONT TestIsValidUploadKey/traversal_nar1522=== CONT TestIsValidUploadKey/traversal1523=== CONT TestIsValidUploadKey/listing_key,_narinfo_type1524=== CONT TestIsValidUploadKey/nar_key,_narinfo_type1525=== CONT TestIsValidUploadKey/narinfo_key,_nar_type1526=== CONT TestIsValidUploadKey/index.html1527=== CONT TestIsValidUploadKey/build_log_home-manager_file1528=== CONT TestIsValidUploadKey/build_log1529=== CONT TestIsValidUploadKey/listing1530=== CONT TestIsValidUploadKey/nar_plain1531=== CONT TestIsValidUploadKey/nar_xz1532=== CONT TestIsValidUploadKey/nar_zst1533=== CONT TestIsValidUploadKey/realisation1534=== CONT TestIsValidUploadKey/nix-cache-info1535=== CONT TestIsValidUploadKey/realisation_plus_in_output1536=== CONT TestIsValidUploadKey/build_log_equals1537=== CONT TestIsValidUploadKey/build_log_question_mark1538=== CONT TestIsValidUploadKey/build_log_plus_in_name1539--- PASS: TestIsValidUploadKey (0.00s)1540 --- PASS: TestIsValidUploadKey/narinfo (0.00s)1541 --- PASS: TestIsValidUploadKey/unknown_type (0.00s)1542 --- PASS: TestIsValidUploadKey/empty_key (0.00s)1543 --- PASS: TestIsValidUploadKey/absolute (0.00s)1544 --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)1545 --- PASS: TestIsValidUploadKey/traversal (0.00s)1546 --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)1547 --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)1548 --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)1549 --- PASS: TestIsValidUploadKey/index.html (0.00s)1550 --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)1551 --- PASS: TestIsValidUploadKey/build_log (0.00s)1552 --- PASS: TestIsValidUploadKey/listing (0.00s)1553 --- PASS: TestIsValidUploadKey/nar_plain (0.00s)1554 --- PASS: TestIsValidUploadKey/nar_xz (0.00s)1555 --- PASS: TestIsValidUploadKey/nar_zst (0.00s)1556 --- PASS: TestIsValidUploadKey/realisation (0.00s)1557 --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)1558 --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)1559 --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)1560 --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)1561 --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)1562=== CONT TestResolveDBConnectionString/flag_wins1563=== CONT TestResolveDBConnectionString/PGHOST_allows_empty1564=== CONT TestResolveDBConnectionString/nothing_configured1565=== CONT TestResolveDBConnectionString/missing_file_is_an_error1566=== CONT TestResolveDBConnectionString/file_when_flag_empty1567=== CONT TestUploadHandlersRejectOversizedBody/create_pending_closure15682026/10/06 08:16:05 INFO Received uploads request method=POST path=/1569--- PASS: TestResolveDBConnectionString (0.00s)1570 --- PASS: TestResolveDBConnectionString/flag_wins (0.00s)1571 --- PASS: TestResolveDBConnectionString/PGHOST_allows_empty (0.00s)1572 --- PASS: TestResolveDBConnectionString/nothing_configured (0.00s)1573 --- PASS: TestResolveDBConnectionString/missing_file_is_an_error (0.00s)1574 --- PASS: TestResolveDBConnectionString/file_when_flag_empty (0.00s)1575=== NAME TestClientIntegration1576 client_integration_test.go:286: Created store path: /build/TestClientIntegration3200774295/002/store/njx5xhy8vaxi8xq8fxbw7skazw9yj829-test-file.txt15772026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/1s7yghixzpinyqm7g59kyn3sav81pmnsdi5v63m6x4r2vacy907j.nar.zst error="server returned 404: 404 page not found\n"15782026/10/06 08:16:05 WARN Failed to register uploaded object key=kq6sdrg0kbc1zwq7zkdy3vnw1lw80xnx.ls error="server returned 404: 404 page not found\n"15792026/10/06 08:16:05 WARN Failed to register uploaded object key=log/6l3lkc9vmiiah74kc43wr8xzrki9pk2a-test-script.drv error="server returned 404: 404 page not found\n"15802026/10/06 08:16:05 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign15812026/10/06 08:16:05 INFO Received complete push request method=POST path=/api/pushes/2/complete15822026/10/06 08:16:05 WARN Failed to register uploaded object key=wpn760pi482hcvi07742wcma585r324v.narinfo error="server returned 404: 404 page not found\n"15832026/10/06 08:16:05 INFO Signed narinfos id=1 count=115842026/10/06 08:16:05 INFO Uploading 1 narinfos15852026/10/06 08:16:05 INFO Upload complete. (72ms)15862026/10/06 08:16:05 INFO Received complete push request method=POST path=/api/pushes/1/complete15872026/10/06 08:16:05 WARN Failed to register uploaded object key=kq6sdrg0kbc1zwq7zkdy3vnw1lw80xnx.narinfo error="server returned 404: 404 page not found\n"15882026/10/06 08:16:05 INFO Upload complete. (69ms)1589=== NAME TestClientWithDependencies1590 client_integration_test.go:617: Skipping nix copy test - isolated store (/build/TestClientWithDependencies1253209235/001/store) requires matching store prefix1591--- PASS: TestClientWithDependencies (0.55s)1592=== CONT TestUploadHandlersRejectOversizedBody/request_more_parts15932026/10/06 08:16:05 INFO Received request for more parts method=POST path=/1594=== RUN TestService_RequireScope_OIDC/builder_may_write1595=== PAUSE TestService_RequireScope_OIDC/builder_may_write1596=== RUN TestService_RequireScope_OIDC/builder_may_not_admin1597=== PAUSE TestService_RequireScope_OIDC/builder_may_not_admin1598=== RUN TestService_RequireScope_OIDC/ops_may_admin1599=== PAUSE TestService_RequireScope_OIDC/ops_may_admin1600=== RUN TestService_RequireScope_OIDC/ops_may_not_write1601=== PAUSE TestService_RequireScope_OIDC/ops_may_not_write1602=== RUN TestService_RequireScope_OIDC/reader_may_not_write1603=== PAUSE TestService_RequireScope_OIDC/reader_may_not_write1604=== RUN TestService_RequireScope_OIDC/static_token_may_admin1605=== PAUSE TestService_RequireScope_OIDC/static_token_may_admin1606=== RUN TestService_RequireScope_OIDC/static_token_may_write1607=== PAUSE TestService_RequireScope_OIDC/static_token_may_write1608=== RUN TestService_RequireScope_OIDC/reader_may_read1609=== PAUSE TestService_RequireScope_OIDC/reader_may_read1610=== RUN TestService_RequireScope_OIDC/writer_implies_read1611=== PAUSE TestService_RequireScope_OIDC/writer_implies_read1612=== RUN TestService_RequireScope_OIDC/anonymous_may_not_read1613=== PAUSE TestService_RequireScope_OIDC/anonymous_may_not_read1614=== RUN TestService_RequireScope_OIDC/reader_may_read_with_netrc1615=== PAUSE TestService_RequireScope_OIDC/reader_may_read_with_netrc1616=== RUN TestService_RequireScope_OIDC/static_token_may_admin_with_netrc1617=== PAUSE TestService_RequireScope_OIDC/static_token_may_admin_with_netrc1618=== RUN TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read1619=== PAUSE TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read1620=== RUN TestService_RequireScope_OIDC/empty_netrc_password_may_not_read1621=== PAUSE TestService_RequireScope_OIDC/empty_netrc_password_may_not_read1622=== CONT TestUploadHandlersRejectOversizedBody/complete_multipart16232026/10/06 08:16:05 INFO Received complete multipart upload request method=POST path=/16242026/10/06 08:16:05 INFO Starting cleanup of old closures method=DELETE path=/api/closures16252026/10/06 08:16:05 INFO Garbage collection started16262026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes16272026/10/06 08:16:05 INFO Aborted multipart uploads count=0 kept=016282026/10/06 08:16:05 WARN Force mode enabled - objects will be deleted immediately without grace period1629--- PASS: TestCacheStatsHandler (0.31s)1630=== CONT TestPush_RejectsBadRequests/no_objects16312026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes1632=== CONT TestPush_RejectsBadRequests/root_not_in_objects16332026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes1634=== CONT TestPush_RejectsBadRequests/no_roots16352026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes1636=== CONT TestPush_RejectsBadRequests/bad_root16372026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes1638--- PASS: TestPush_RejectsBadRequests (0.35s)1639 --- PASS: TestPush_RejectsBadRequests/no_objects (0.00s)1640 --- PASS: TestPush_RejectsBadRequests/root_not_in_objects (0.00s)1641 --- PASS: TestPush_RejectsBadRequests/no_roots (0.00s)1642 --- PASS: TestPush_RejectsBadRequests/bad_root (0.00s)1643=== CONT TestForceGCDuringPushOffersSweptObject/before_pending_rows16442026/10/06 08:16:05 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)16452026/10/06 08:16:05 INFO Uploading 0p25x86q9kxnifhjs7dk2gf2xxi14a4y-test-file-1.txt (160B)16462026/10/06 08:16:05 INFO Uploading sp8021z5lp9m36lzpnx8s0736nf2pg3r-test-file-0.txt (160B)16472026/10/06 08:16:05 INFO Uploading k5px2d8zq0qgmbicpgssjvxf48c3f0mc-test-file-2.txt (160B)1648=== CONT TestForceGCDuringPushOffersSweptObject/after_presence_check16492026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/0ahq3izw3h0b0c08p3xdfclh758fwxnp85ccvflasb4vnfsv50ri.nar.zst error="server returned 404: 404 page not found\n"16502026/10/06 08:16:05 WARN Failed to register uploaded object key=0p25x86q9kxnifhjs7dk2gf2xxi14a4y.ls error="server returned 404: 404 page not found\n"16512026/10/06 08:16:05 INFO Received push request method=POST path=/api/pushes16522026/10/06 08:16:05 WARN Failed to register uploaded object key=sp8021z5lp9m36lzpnx8s0736nf2pg3r.ls error="server returned 404: 404 page not found\n"16532026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/13p9pqi0ga6fx2apcwls9hjnv7nmf740ggpn5zvfzdsp5vz2bbg4.nar.zst error="server returned 404: 404 page not found\n"16542026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/1823g6vx4q5zqvr73m7lkq1wpk40fin7bsc792di430yk7sg4lka.nar.zst error="server returned 404: 404 page not found\n"16552026/10/06 08:16:05 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign16562026/10/06 08:16:05 WARN Failed to register uploaded object key=k5px2d8zq0qgmbicpgssjvxf48c3f0mc.ls error="server returned 404: 404 page not found\n"16572026/10/06 08:16:05 INFO Signed narinfos id=1 count=316582026/10/06 08:16:05 INFO Uploading 3 narinfos16592026/10/06 08:16:05 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)16602026/10/06 08:16:05 INFO Uploading njx5xhy8vaxi8xq8fxbw7skazw9yj829-test-file.txt (152B)16612026/10/06 08:16:05 WARN Failed to register uploaded object key=k5px2d8zq0qgmbicpgssjvxf48c3f0mc.narinfo error="server returned 404: 404 page not found\n"16622026/10/06 08:16:05 WARN Failed to register uploaded object key=sp8021z5lp9m36lzpnx8s0736nf2pg3r.narinfo error="server returned 404: 404 page not found\n"16632026/10/06 08:16:05 INFO Received complete push request method=POST path=/api/pushes/1/complete16642026/10/06 08:16:05 WARN Failed to register uploaded object key=0p25x86q9kxnifhjs7dk2gf2xxi14a4y.narinfo error="server returned 404: 404 page not found\n"16652026/10/06 08:16:05 WARN Failed to register uploaded object key=njx5xhy8vaxi8xq8fxbw7skazw9yj829.ls error="server returned 404: 404 page not found\n"16662026/10/06 08:16:05 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign16672026/10/06 08:16:05 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=016682026/10/06 08:16:05 WARN Failed to register uploaded object key=nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst error="server returned 404: 404 page not found\n"16692026/10/06 08:16:05 INFO Signed narinfos id=1 count=116702026/10/06 08:16:05 INFO Uploading 1 narinfos16712026/10/06 08:16:05 INFO Vacuumed table table=pending_closures16722026/10/06 08:16:05 INFO Upload complete. (74ms)1673=== NAME TestClientMultipleUploads1674 client_integration_test.go:369: Uploaded 3 paths in 107.55016ms16752026/10/06 08:16:05 INFO Received complete push request method=POST path=/api/pushes/1/complete16762026/10/06 08:16:05 WARN Failed to register uploaded object key=njx5xhy8vaxi8xq8fxbw7skazw9yj829.narinfo error="server returned 404: 404 page not found\n"16772026/10/06 08:16:05 INFO Vacuumed table table=pending_objects16782026/10/06 08:16:05 INFO Vacuumed table table=multipart_uploads16792026/10/06 08:16:05 INFO Upload complete. (65ms)1680--- PASS: TestClientMultipleUploads (0.50s)16812026/10/06 08:16:05 INFO Vacuumed table table=closures1682=== CONT TestParseSingleRange/none1683=== CONT TestParseSingleRange/start_past_EOF1684=== CONT TestParseSingleRange/single_byte1685=== CONT TestParseSingleRange/suffix_exceeds_size1686=== CONT TestParseSingleRange/suffix1687=== CONT TestParseSingleRange/end_clamped_to_size1688=== CONT TestParseSingleRange/open-ended1689=== CONT TestParseSingleRange/closed1690=== CONT TestParseSingleRange/start_far_past_EOF1691=== CONT TestParseSingleRange/malformed_end_before_start1692=== CONT TestParseSingleRange/multi-range_ignored1693=== CONT TestParseSingleRange/unknown_unit1694=== CONT TestParseSingleRange/malformed_no_dash1695=== CONT TestParseSingleRange/malformed_both_empty1696--- PASS: TestParseSingleRange (0.00s)1697 --- PASS: TestParseSingleRange/none (0.00s)1698 --- PASS: TestParseSingleRange/start_past_EOF (0.00s)1699 --- PASS: TestParseSingleRange/single_byte (0.00s)1700 --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)1701 --- PASS: TestParseSingleRange/suffix (0.00s)1702 --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)1703 --- PASS: TestParseSingleRange/open-ended (0.00s)1704 --- PASS: TestParseSingleRange/closed (0.00s)1705 --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)1706 --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)1707 --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)1708 --- PASS: TestParseSingleRange/unknown_unit (0.00s)1709 --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)1710 --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)1711=== CONT TestIsValidCachePath/narinfo1712=== CONT TestIsValidCachePath/index.html1713=== CONT TestIsValidCachePath/short_hash1714=== CONT TestIsValidCachePath/wrong_extension1715=== CONT TestIsValidCachePath/leading_slash1716=== CONT TestIsValidCachePath/empty1717=== CONT TestIsValidCachePath/random_path1718=== CONT TestIsValidCachePath/invalid_char_u1719=== CONT TestIsValidCachePath/invalid_char_e1720=== CONT TestIsValidCachePath/traversal_in_middle1721=== CONT TestIsValidCachePath/traversal_parent1722=== CONT TestIsValidCachePath/nar_uncompressed1723=== CONT TestIsValidCachePath/nix-cache-info1724=== CONT TestIsValidCachePath/realisation1725=== CONT TestIsValidCachePath/log1726=== CONT TestIsValidCachePath/ls1727=== CONT TestIsValidCachePath/nar_xz1728=== CONT TestIsValidCachePath/nar_bz21729=== CONT TestIsValidCachePath/nar_zst1730=== CONT TestIsValidCachePath/narinfo_all_nix_base32_chars1731=== CONT TestClientErrorHandling/InvalidStorePath1732--- PASS: TestIsValidCachePath (0.00s)1733 --- PASS: TestIsValidCachePath/narinfo (0.00s)1734 --- PASS: TestIsValidCachePath/index.html (0.00s)1735 --- PASS: TestIsValidCachePath/short_hash (0.00s)1736 --- PASS: TestIsValidCachePath/wrong_extension (0.00s)1737 --- PASS: TestIsValidCachePath/leading_slash (0.00s)1738 --- PASS: TestIsValidCachePath/empty (0.00s)1739 --- PASS: TestIsValidCachePath/random_path (0.00s)1740 --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)1741 --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)1742 --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)1743 --- PASS: TestIsValidCachePath/traversal_parent (0.00s)1744 --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)1745 --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)1746 --- PASS: TestIsValidCachePath/realisation (0.00s)1747 --- PASS: TestIsValidCachePath/log (0.00s)1748 --- PASS: TestIsValidCachePath/ls (0.00s)1749 --- PASS: TestIsValidCachePath/nar_xz (0.00s)1750 --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)1751 --- PASS: TestIsValidCachePath/nar_zst (0.00s)1752 --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)17532026/10/06 08:16:05 INFO Vacuumed table table=objects1754--- PASS: TestService_ReadAuthMiddleware (0.27s)1755=== CONT TestClientErrorHandling/InvalidAuthToken1756=== CONT TestClientErrorHandling/ServerNotAvailable17572026/10/06 08:16:05 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"17582026/10/06 08:16:05 WARN mTLS auth: bound subjects configured but subject DN unavailable17592026/10/06 08:16:05 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"1760--- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (0.24s)1761=== CONT TestServerTLSConfig/no_client_CA1762=== CONT TestServerTLSConfig/not_a_PEM_file1763=== CONT TestServerTLSConfig/missing_CA_file1764--- PASS: TestServerTLSConfig (0.00s)1765 --- PASS: TestServerTLSConfig/no_client_CA (0.00s)1766 --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)1767 --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)1768=== CONT TestCacheConfigHandler/full_config,_no_issuer1769=== CONT TestCacheConfigHandler/no_signing_keys1770=== CONT TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator1771=== CONT TestCacheConfigHandler/no_cache_url_configured1772--- PASS: TestCacheConfigHandler (0.00s)1773 --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)1774 --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)1775 --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)1776 --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)1777=== CONT TestService_RequireScope_OIDC/builder_may_write1778=== CONT TestService_RequireScope_OIDC/static_token_may_admin_with_netrc1779=== CONT TestService_RequireScope_OIDC/empty_netrc_password_may_not_read1780=== CONT TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read17812026/10/06 08:16:05 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]1782=== CONT TestService_RequireScope_OIDC/anonymous_may_not_read1783=== CONT TestService_RequireScope_OIDC/reader_may_read1784=== CONT TestService_RequireScope_OIDC/reader_may_not_write1785=== CONT TestService_RequireScope_OIDC/writer_implies_read1786=== CONT TestService_RequireScope_OIDC/static_token_may_write1787=== CONT TestService_RequireScope_OIDC/static_token_may_admin1788=== CONT TestService_RequireScope_OIDC/ops_may_admin1789=== CONT TestService_RequireScope_OIDC/ops_may_not_write1790=== CONT TestService_RequireScope_OIDC/reader_may_read_with_netrc1791=== CONT TestService_RequireScope_OIDC/builder_may_not_admin1792--- PASS: TestService_RequireScope_OIDC (0.56s)1793 --- PASS: TestService_RequireScope_OIDC/builder_may_write (0.00s)1794 --- PASS: TestService_RequireScope_OIDC/static_token_may_admin_with_netrc (0.00s)1795 --- PASS: TestService_RequireScope_OIDC/empty_netrc_password_may_not_read (0.00s)1796 --- PASS: TestService_RequireScope_OIDC/wrong_netrc_password_may_not_read (0.00s)1797 --- PASS: TestService_RequireScope_OIDC/anonymous_may_not_read (0.00s)1798 --- PASS: TestService_RequireScope_OIDC/reader_may_read (0.00s)1799 --- PASS: TestService_RequireScope_OIDC/reader_may_not_write (0.00s)1800 --- PASS: TestService_RequireScope_OIDC/writer_implies_read (0.00s)1801 --- PASS: TestService_RequireScope_OIDC/static_token_may_write (0.00s)1802 --- PASS: TestService_RequireScope_OIDC/static_token_may_admin (0.00s)1803 --- PASS: TestService_RequireScope_OIDC/ops_may_admin (0.00s)1804 --- PASS: TestService_RequireScope_OIDC/ops_may_not_write (0.00s)1805 --- PASS: TestService_RequireScope_OIDC/reader_may_read_with_netrc (0.00s)1806 --- PASS: TestService_RequireScope_OIDC/builder_may_not_admin (0.00s)18072026/10/06 08:16:05 INFO All 1 paths already cached1808=== NAME TestClientIntegration1809 client_integration_test.go:312: Retrieved narinfo from S3:1810 StorePath: /build/TestClientIntegration3200774295/002/store/njx5xhy8vaxi8xq8fxbw7skazw9yj829-test-file.txt1811 URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst1812 Compression: zstd1813 NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11814 NarSize: 1521815 References: 1816 CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk11817 client_integration_test.go:313: Retrieved .ls file from S3 (compressed size: 77 bytes)1818 client_integration_test.go:313: Decompressed .ls content (64 bytes):1819 {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}1820 client_integration_test.go:316: Testing garbage collection...18212026/10/06 08:16:05 INFO lead: released remote=192.0.2.1:12341822--- PASS: TestLeadEndsOnShutdown (1.53s)1823--- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.24s)1824=== NAME TestClientCADerivations1825 client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations198228233/001/store/cvn55q2sby5xcgx30bx6c0f1xxacwvs3-ca-test18262026/10/06 08:16:05 WARN readiness check failed error="closed pool"1827--- PASS: TestService_readinessHandler (0.25s)18282026/10/06 08:16:05 INFO Starting cleanup of old closures method=DELETE path=/api/closures18292026/10/06 08:16:05 INFO Garbage collection started18302026/10/06 08:16:05 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/present18312026/10/06 08:16:05 INFO Aborted multipart uploads count=0 kept=018322026/10/06 08:16:05 WARN Force mode enabled - objects will be deleted immediately without grace period1833=== NAME TestClientCADerivations1834 client_ca_test.go:139: Found 1 dependencies (including self)18352026/10/06 08:16:06 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=018362026/10/06 08:16:06 INFO Aborted multipart uploads count=0 kept=018372026/10/06 08:16:06 INFO Vacuumed table table=pending_closures18382026/10/06 08:16:06 WARN Force mode enabled - objects will be deleted immediately without grace period18392026/10/06 08:16:06 INFO Vacuumed table table=pending_objects18402026/10/06 08:16:06 INFO Vacuumed table table=multipart_uploads18412026-10-06 08:16:06.026 UTC [1565] ERROR: canceling statement due to user request18422026-10-06 08:16:06.026 UTC [1565] CONTEXT: PL/pgSQL expression "0"1843 PL/pgSQL function object_stats_apply() line 4 during statement block local variable initialization18442026-10-06 08:16:06.026 UTC [1565] STATEMENT: -- name: MarkStaleObjects :execrows1845 WITH RECURSIVE ct AS (1846 SELECT timezone('UTC', now()) AS now1847 ),1848 closure_reach AS (1849 -- Start with all closure keys1850 SELECT o.key, o.refs1851 FROM objects o1852 INNER JOIN closures c ON o.key = c.key1853 UNION1854 -- Recursively add all referenced objects1855 SELECT o.key, o.refs1856 FROM objects o1857 INNER JOIN closure_reach cr ON o.key = ANY(cr.refs)1858 ),1859 reachable_objects AS (1860 SELECT DISTINCT key FROM closure_reach1861 ),1862 stale_objects AS (1863 SELECT o.key1864 FROM objects AS o, ct1865 WHERE1866 NOT EXISTS (1867 SELECT 11868 FROM reachable_objects ro1869 WHERE ro.key = o.key1870 )1871 AND NOT EXISTS (1872 SELECT 11873 FROM pending_objects AS po1874 WHERE po.key = o.key1875 )1876 AND o.deleted_at IS NULL -- Only mark fresh objects1877 ORDER BY o.key COLLATE "C" -- lock in key order, like commit_pending_closure1878 FOR UPDATE1879 )1880 UPDATE objects1881 SET1882 deleted_at = ct.now,1883 first_deleted_at = COALESCE(first_deleted_at, ct.now)1884 FROM stale_objects, ct1885 WHERE objects.key = stale_objects.key1886 18872026/10/06 08:16:06 INFO Vacuumed table table=closures18882026/10/06 08:16:06 INFO Vacuumed table table=objects18892026/10/06 08:16:06 INFO Aborted multipart uploads count=0 kept=018902026/10/06 08:16:06 WARN Force mode enabled - objects will be deleted immediately without grace period1891=== RUN TestService_AuthMiddleware_OIDC/valid_OIDC_token1892=== PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token1893=== RUN TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1894=== PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected1895=== RUN TestService_AuthMiddleware_OIDC/malformed_token_rejected1896=== PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected1897=== RUN TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1898=== PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1899=== CONT TestService_AuthMiddleware_OIDC/valid_OIDC_token1900=== CONT TestService_AuthMiddleware_OIDC/malformed_token_rejected19012026/10/06 08:16:06 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]1902=== CONT TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured1903=== CONT TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected19042026/10/06 08:16:06 ERROR failed to remove object object=nar/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.nar.zst error="Post \"http://localhost:44277/bucket85/?delete=\": context canceled"19052026/10/06 08:16:06 WARN Authentication failed token_preview=eyJhbGciOi...vuMM6SgmMA token_length=701 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]1906--- PASS: TestGCEndsOnShutdown (0.00s)1907 --- PASS: TestGCEndsOnShutdown/before_the_run (0.30s)1908 --- PASS: TestGCEndsOnShutdown/blocked_on_a_row_lock_in_the_mark (0.29s)1909 --- PASS: TestGCEndsOnShutdown/blocked_in_the_sweep's_S3_delete (0.32s)1910--- PASS: TestService_AuthMiddleware_OIDC (0.39s)1911 --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)1912 --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)1913 --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)1914 --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)19152026/10/06 08:16:06 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=215.708671ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19162026/10/06 08:16:06 INFO Received uploads request method=POST path=/api/pending_closures19172026/10/06 08:16:06 INFO Received push request method=POST path=/api/pushes19182026/10/06 08:16:06 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)19192026/10/06 08:16:06 INFO Uploading cvn55q2sby5xcgx30bx6c0f1xxacwvs3-ca-test (144B)19202026/10/06 08:16:06 INFO Received uploads request method=POST path=/api/pending_closures19212026/10/06 08:16:06 WARN Failed to register uploaded object key=cvn55q2sby5xcgx30bx6c0f1xxacwvs3.ls error="server returned 404: 404 page not found\n"19222026/10/06 08:16:06 WARN Failed to register uploaded object key=nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst error="server returned 404: 404 page not found\n"19232026/10/06 08:16:06 WARN Failed to register uploaded object key=log/474ii0sm9fn6j9zh7y6mfdgzhb6r5r8p-ca-test.drv error="server returned 404: 404 page not found\n"19242026/10/06 08:16:06 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign19252026/10/06 08:16:06 INFO Signed narinfos id=1 count=119262026/10/06 08:16:06 INFO Uploading 1 narinfos19272026/10/06 08:16:06 INFO Aborted multipart uploads count=0 kept=019282026/10/06 08:16:06 INFO Received complete push request method=POST path=/api/pushes/1/complete19292026/10/06 08:16:06 WARN Failed to register uploaded object key=cvn55q2sby5xcgx30bx6c0f1xxacwvs3.narinfo error="server returned 404: 404 page not found\n"19302026/10/06 08:16:06 WARN Force mode enabled - objects will be deleted immediately without grace period19312026/10/06 08:16:06 INFO Upload complete. (97ms)1932=== NAME TestClientCADerivations1933 client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations198228233/001/store/cvn55q2sby5xcgx30bx6c0f1xxacwvs3-ca-test1934 URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst1935 Compression: zstd1936 NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1937 NarSize: 1441938 References: 1939 Deriver: /build/TestClientCADerivations198228233/001/store/474ii0sm9fn6j9zh7y6mfdgzhb6r5r8p-ca-test.drv1940 CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n1941 client_ca_test.go:185: Checking for realisation files in S3...1942 client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations1943 client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache19442026/10/06 08:16:06 INFO Received uploads request method=POST path=/api/pending_closures19452026/10/06 08:16:06 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=019462026/10/06 08:16:06 INFO Vacuumed table table=pending_closures19472026/10/06 08:16:06 INFO Vacuumed table table=pending_objects19482026/10/06 08:16:06 INFO Vacuumed table table=multipart_uploads19492026/10/06 08:16:06 INFO Vacuumed table table=closures19502026/10/06 08:16:06 INFO Vacuumed table table=objects19512026/10/06 08:16:06 INFO Aborted multipart uploads count=0 kept=019522026/10/06 08:16:06 WARN Force mode enabled - objects will be deleted immediately without grace period19532026/10/06 08:16:06 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=019542026/10/06 08:16:06 INFO Vacuumed table table=pending_closures19552026/10/06 08:16:06 INFO Vacuumed table table=pending_objects19562026/10/06 08:16:06 INFO Vacuumed table table=multipart_uploads19572026/10/06 08:16:06 INFO Vacuumed table table=closures19582026/10/06 08:16:06 INFO Vacuumed table table=objects1959--- PASS: TestForceGCDuringPushOffersSweptObject (0.00s)1960 --- PASS: TestForceGCDuringPushOffersSweptObject/before_pending_rows (0.32s)1961 --- PASS: TestForceGCDuringPushOffersSweptObject/after_presence_check (0.32s)19622026/10/06 08:16:06 INFO lead: released remote=192.0.2.1:12341963=== NAME TestClientCADerivations1964 client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features1965 warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable1966 error: binary cache 's3://bucket78?endpoint=http://localhost:44277®ion=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations198228233/001/store'1967 client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 11968--- PASS: TestClientCADerivations (0.61s)19692026/10/06 08:16:06 INFO lead: acquired remote=192.0.2.1:123419702026/10/06 08:16:06 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=384.202305ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present19712026/10/06 08:16:06 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"19722026/10/06 08:16:06 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"19732026/10/06 08:16:06 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=850.608154ms error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present1974--- PASS: TestUploadHandlersRejectOversizedBody (0.15s)1975 --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.05s)1976 --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.07s)1977 --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (1.04s)1978--- PASS: TestPendingClosureWriteTimeout (0.01s)1979 --- PASS: TestPendingClosureWriteTimeout/empty (0.00s)1980 --- PASS: TestPendingClosureWriteTimeout/670k_objects (0.00s)1981 --- PASS: TestPendingClosureWriteTimeout/400_objects (0.00s)1982 --- PASS: TestPendingClosureWriteTimeout/handler_extends_the_server's_deadline (1.12s)1983=== NAME TestOrphanedObjectsGCStressTest1984 orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains1985 orphaned_objects_gc_test.go:446: Marked 210 objects for deletion1986 orphaned_objects_gc_test.go:509: Stress test completed successfully:1987 orphaned_objects_gc_test.go:510: - Active objects preserved: 201988 orphaned_objects_gc_test.go:511: - Objects deleted: 2101989 orphaned_objects_gc_test.go:512: - Total GC'd: 2101990--- PASS: TestOrphanedObjectsGCStressTest (2.34s)19912026/10/06 08:16:07 INFO lead: released remote=192.0.2.1:12341992--- PASS: TestLeadElectsOneAndHandsOver (2.87s)19932026/10/06 08:16:07 WARN Rate limiter enabled after throttle name=s3-test rate=519942026/10/06 08:16:07 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."1995=== NAME TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle1996 throttle_test.go:213: Proxy stats: total=13, throttled=10, completeMultipart=101997 throttle_test.go:215: Rate limiter: enabled=true, rate=5.001998--- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (3.93s)19992026/10/06 08:16:07 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.600919107s error="Post \"http://localhost:19999/api/objects/present\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/objects/present20002026/10/06 08:16:07 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=3 objects_failed=020012026/10/06 08:16:07 INFO Received create pin request method=POST path=/api/pins/myapp20022026/10/06 08:16:07 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC2428553955/001/store/02316i01sz2q923vcw125wvigag9iry9-pinned-file.txt narinfo_key=02316i01sz2q923vcw125wvigag9iry9.narinfo20032026/10/06 08:16:07 INFO Starting cleanup of old closures method=DELETE path=/api/closures20042026/10/06 08:16:07 INFO Garbage collection started20052026/10/06 08:16:07 INFO Aborted multipart uploads count=0 kept=020062026/10/06 08:16:07 WARN Force mode enabled - objects will be deleted immediately without grace period20072026/10/06 08:16:07 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=020082026/10/06 08:16:07 INFO Vacuumed table table=pending_closures20092026/10/06 08:16:07 INFO Vacuumed table table=pending_objects20102026/10/06 08:16:07 INFO Vacuumed table table=multipart_uploads20112026/10/06 08:16:07 INFO Vacuumed table table=closures20122026/10/06 08:16:07 INFO Vacuumed table table=objects20132026/10/06 08:16:07 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=3 objects_failed=02014=== NAME TestClientIntegration2015 client_integration_test.go:323: Objects in database after GC:2016 client_integration_test.go:323: Successfully deleted all objects with GC --force2017--- PASS: TestClientIntegration (2.56s)2018--- PASS: TestReadProxyOutlastsServerWriteTimeout (4.72s)20192026/10/06 08:16:09 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-config20202026/10/06 08:16:09 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=188.482541ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20212026/10/06 08:16:09 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=436.033894ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config2022--- PASS: TestConnectWaitsForAPeerMigration (5.14s)2023=== NAME TestPinProtectsFromGC2024 client_integration_test.go:840: Pin successfully protected closure from garbage collection2025--- PASS: TestPinProtectsFromGC (4.78s)20262026/10/06 08:16:09 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=744.512809ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20272026/10/06 08:16:10 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.58565529s error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20282026/10/06 08:16:11 INFO Aborted multipart uploads count=1 kept=020292026/10/06 08:16:11 INFO Received cleanup request method=DELETE path=/api/pending_closures20302026/10/06 08:16:11 INFO Aborted multipart uploads count=1 kept=02031--- PASS: TestPendingCleanupUsesOneCutoff (6.48s)20322026/10/06 08:16:12 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"20332026/10/06 08:16:12 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-config20342026/10/06 08:16:12 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=203.181511ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20352026/10/06 08:16:12 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=413.730046ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20362026/10/06 08:16:13 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=812.481868ms error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20372026/10/06 08:16:13 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.600594502s error="Get \"http://localhost:19999/api/cache-config\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/cache-config20382026/10/06 08:16:15 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_closures20392026/10/06 08:16:15 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=192.562106ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures20402026/10/06 08:16:15 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=414.777538ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures20412026/10/06 08:16:16 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=801.165922ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures20422026/10/06 08:16:16 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.513613708s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures2043--- PASS: TestClientErrorHandling (0.00s)2044 --- PASS: TestClientErrorHandling/InvalidStorePath (0.30s)2045 --- PASS: TestClientErrorHandling/InvalidAuthToken (0.51s)2046 --- PASS: TestClientErrorHandling/ServerNotAvailable (12.56s)2047PASS20482026-10-06 08:16:18.741 UTC [128] LOG: received smart shutdown request20492026-10-06 08:16:18.745 UTC [128] LOG: background worker "logical replication launcher" (PID 138) exited with exit code 120502026-10-06 08:16:18.757 UTC [133] LOG: shutting down20512026-10-06 08:16:18.757 UTC [133] LOG: checkpoint starting: shutdown immediate20522026-10-06 08:16:19.206 UTC [133] LOG: checkpoint complete: wrote 11086 buffers (67.7%), wrote 5 SLRU buffers; 0 WAL file(s) added, 0 removed, 26 recycled; write=0.208 s, sync=0.217 s, total=0.450 s; sync files=31000, longest=0.001 s, average=0.001 s; distance=428428 kB, estimate=428428 kB; lsn=0/1B9C8CE8, redo lsn=0/1B9C8CE820532026-10-06 08:16:19.284 UTC [128] LOG: database system is shut down2054Running OIDC tests...2055=== RUN TestAudienceForIssuer2056=== PAUSE TestAudienceForIssuer2057=== RUN TestGlobMatch2058=== PAUSE TestGlobMatch2059=== RUN TestValidateToken_ValidToken2060=== PAUSE TestValidateToken_ValidToken2061=== RUN TestValidateToken_WrongAudience2062=== PAUSE TestValidateToken_WrongAudience2063=== RUN TestValidateToken_Expired2064=== PAUSE TestValidateToken_Expired2065=== RUN TestValidateToken_BoundClaimsMismatch2066=== PAUSE TestValidateToken_BoundClaimsMismatch2067=== RUN TestValidateToken_BoundSubjectMismatch2068=== PAUSE TestValidateToken_BoundSubjectMismatch2069=== RUN TestValidateToken_MultipleProviders2070=== PAUSE TestValidateToken_MultipleProviders2071=== RUN TestValidateToken_NoMatchingProvider2072=== PAUSE TestValidateToken_NoMatchingProvider2073=== RUN TestValidateToken_KubernetesServiceAccount2074=== PAUSE TestValidateToken_KubernetesServiceAccount2075=== RUN TestNewValidator_KubernetesRequiresCA2076=== PAUSE TestNewValidator_KubernetesRequiresCA2077=== RUN TestValidateToken_KubernetesIssuerFromOwnToken2078=== PAUSE TestValidateToken_KubernetesIssuerFromOwnToken2079=== RUN TestPins_ReservedForMatchingRule2080=== PAUSE TestPins_ReservedForMatchingRule2081=== RUN TestPins_TopLevelShorthand2082=== PAUSE TestPins_TopLevelShorthand2083=== RUN TestPins_ConfigValidation2084=== PAUSE TestPins_ConfigValidation2085=== RUN TestScopes_LegacyProviderDefaultsToWrite2086=== PAUSE TestScopes_LegacyProviderDefaultsToWrite2087=== RUN TestScopes_Rules2088=== PAUSE TestScopes_Rules2089=== RUN TestScopes_ConfigValidation2090=== PAUSE TestScopes_ConfigValidation2091=== CONT TestAudienceForIssuer2092=== CONT TestValidateToken_KubernetesServiceAccount2093=== CONT TestPins_ReservedForMatchingRule2094=== CONT TestValidateToken_WrongAudience2095=== CONT TestValidateToken_NoMatchingProvider2096=== CONT TestValidateToken_MultipleProviders2097=== CONT TestValidateToken_BoundSubjectMismatch2098=== CONT TestValidateToken_ValidToken2099=== CONT TestGlobMatch2100=== RUN TestGlobMatch/foo_foo2101=== PAUSE TestGlobMatch/foo_foo2102=== RUN TestGlobMatch/foo_bar2103=== PAUSE TestGlobMatch/foo_bar2104=== RUN TestGlobMatch/*_2105=== CONT TestValidateToken_Expired2106=== CONT TestPins_ConfigValidation2107=== CONT TestScopes_ConfigValidation2108=== CONT TestScopes_Rules2109=== CONT TestScopes_LegacyProviderDefaultsToWrite2110=== CONT TestPins_TopLevelShorthand2111=== CONT TestValidateToken_KubernetesIssuerFromOwnToken2112=== CONT TestNewValidator_KubernetesRequiresCA2113--- PASS: TestAudienceForIssuer (0.00s)2114--- PASS: TestPins_ConfigValidation (0.00s)2115=== CONT TestValidateToken_BoundClaimsMismatch2116=== PAUSE TestGlobMatch/*_2117--- PASS: TestScopes_ConfigValidation (0.00s)2118=== RUN TestGlobMatch/*_anything2119=== PAUSE TestGlobMatch/*_anything2120=== RUN TestGlobMatch/foo*_foo2121=== PAUSE TestGlobMatch/foo*_foo2122=== RUN TestGlobMatch/foo*_foobar2123=== PAUSE TestGlobMatch/foo*_foobar2124=== RUN TestGlobMatch/foo*_bar2125=== PAUSE TestGlobMatch/foo*_bar2126=== RUN TestGlobMatch/*bar_bar2127=== PAUSE TestGlobMatch/*bar_bar2128=== RUN TestGlobMatch/*bar_foobar2129=== PAUSE TestGlobMatch/*bar_foobar2130=== RUN TestGlobMatch/*bar_foo2131=== PAUSE TestGlobMatch/*bar_foo2132=== RUN TestGlobMatch/foo*bar_foobar2133=== PAUSE TestGlobMatch/foo*bar_foobar2134=== RUN TestGlobMatch/foo*bar_foo123bar2135=== PAUSE TestGlobMatch/foo*bar_foo123bar2136=== RUN TestGlobMatch/foo*bar_foobarbaz2137=== PAUSE TestGlobMatch/foo*bar_foobarbaz2138=== RUN TestGlobMatch/*/*_foo/bar2139=== PAUSE TestGlobMatch/*/*_foo/bar2140=== RUN TestGlobMatch/*/*_foo2141=== PAUSE TestGlobMatch/*/*_foo2142=== RUN TestGlobMatch/refs/heads/*_refs/heads/main2143=== PAUSE TestGlobMatch/refs/heads/*_refs/heads/main2144=== RUN TestGlobMatch/refs/heads/*_refs/tags/v1.02145=== PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.02146=== RUN TestGlobMatch/refs/*/main_refs/heads/main2147=== PAUSE TestGlobMatch/refs/*/main_refs/heads/main2148=== RUN TestGlobMatch/fo?_foo2149=== PAUSE TestGlobMatch/fo?_foo2150=== RUN TestGlobMatch/fo?_fo2151=== PAUSE TestGlobMatch/fo?_fo2152=== RUN TestGlobMatch/fo?_fooo2153=== PAUSE TestGlobMatch/fo?_fooo2154=== RUN TestGlobMatch/?oo_foo2155=== PAUSE TestGlobMatch/?oo_foo2156=== RUN TestGlobMatch/?oo_boo2157=== PAUSE TestGlobMatch/?oo_boo2158=== RUN TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2159=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2160=== RUN TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2161=== PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2162=== CONT TestGlobMatch/foo_foo2163=== CONT TestGlobMatch/foo*bar_foo123bar2164=== CONT TestGlobMatch/foo*bar_foobar2165=== CONT TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main2166=== CONT TestGlobMatch/*_2167=== CONT TestGlobMatch/foo_bar2168=== CONT TestGlobMatch/?oo_boo2169=== CONT TestGlobMatch/fo?_fooo2170=== CONT TestGlobMatch/*bar_foo2171=== CONT TestGlobMatch/*bar_foobar2172=== CONT TestGlobMatch/refs/*/main_refs/heads/main2173=== CONT TestGlobMatch/*/*_foo2174=== CONT TestGlobMatch/refs/heads/*_refs/heads/main2175=== CONT TestGlobMatch/*/*_foo/bar2176=== CONT TestGlobMatch/foo*bar_foobarbaz2177=== CONT TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main2178=== CONT TestGlobMatch/*bar_bar2179=== CONT TestGlobMatch/foo*_bar2180=== CONT TestGlobMatch/foo*_foobar2181=== CONT TestGlobMatch/?oo_foo2182=== CONT TestGlobMatch/foo*_foo2183=== CONT TestGlobMatch/*_anything2184=== CONT TestGlobMatch/fo?_fo2185=== CONT TestGlobMatch/fo?_foo2186=== CONT TestGlobMatch/refs/heads/*_refs/tags/v1.02187--- PASS: TestGlobMatch (0.00s)2188 --- PASS: TestGlobMatch/foo_foo (0.00s)2189 --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)2190 --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)2191 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)2192 --- PASS: TestGlobMatch/*_ (0.00s)2193 --- PASS: TestGlobMatch/foo_bar (0.00s)2194 --- PASS: TestGlobMatch/?oo_boo (0.00s)2195 --- PASS: TestGlobMatch/fo?_fooo (0.00s)2196 --- PASS: TestGlobMatch/*bar_foo (0.00s)2197 --- PASS: TestGlobMatch/*bar_foobar (0.00s)2198 --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)2199 --- PASS: TestGlobMatch/*/*_foo (0.00s)2200 --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)2201 --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)2202 --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)2203 --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)2204 --- PASS: TestGlobMatch/*bar_bar (0.00s)2205 --- PASS: TestGlobMatch/foo*_bar (0.00s)2206 --- PASS: TestGlobMatch/foo*_foobar (0.00s)2207 --- PASS: TestGlobMatch/?oo_foo (0.00s)2208 --- PASS: TestGlobMatch/foo*_foo (0.00s)2209 --- PASS: TestGlobMatch/*_anything (0.00s)2210 --- PASS: TestGlobMatch/fo?_fo (0.00s)2211 --- PASS: TestGlobMatch/fo?_foo (0.00s)2212 --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)22132026/10/06 08:16:21 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:37281/oidc2214--- PASS: TestValidateToken_ValidToken (0.04s)22152026/10/06 08:16:21 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:42691/oidc22162026/10/06 08:16:21 INFO OIDC provider initialized name=kubernetes issuer=https://oidc.eks.invalid/id/ABC1232217--- PASS: TestValidateToken_Expired (0.04s)2218--- PASS: TestValidateToken_KubernetesIssuerFromOwnToken (0.05s)22192026/10/06 08:16:21 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:39725/oidc2220--- PASS: TestValidateToken_WrongAudience (0.06s)22212026/10/06 08:16:21 http: TLS handshake error from 127.0.0.1:54310: remote error: tls: bad certificate2222--- PASS: TestNewValidator_KubernetesRequiresCA (0.10s)22232026/10/06 08:16:21 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:38015/oidc2224--- PASS: TestValidateToken_BoundSubjectMismatch (0.11s)22252026/10/06 08:16:21 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:42345/oidc22262026/10/06 08:16:21 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:41767/oidc2227--- PASS: TestScopes_Rules (0.12s)2228--- PASS: TestPins_ReservedForMatchingRule (0.13s)22292026/10/06 08:16:21 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:36261/oidc2230--- PASS: TestPins_TopLevelShorthand (0.14s)22312026/10/06 08:16:21 INFO OIDC provider initialized name=kubernetes issuer=https://127.0.0.1:432092232--- PASS: TestValidateToken_KubernetesServiceAccount (0.17s)22332026/10/06 08:16:21 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:35223/oidc2234--- PASS: TestValidateToken_BoundClaimsMismatch (0.18s)22352026/10/06 08:16:21 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:41651/oidc2236--- PASS: TestScopes_LegacyProviderDefaultsToWrite (0.21s)22372026/10/06 08:16:21 INFO OIDC provider initialized name=provider1 issuer=http://127.0.0.1:36209/oidc2238--- PASS: TestValidateToken_NoMatchingProvider (0.24s)22392026/10/06 08:16:21 INFO OIDC provider initialized name=provider1 issuer=http://127.0.0.1:41985/oidc22402026/10/06 08:16:21 INFO OIDC provider initialized name=provider2 issuer=http://127.0.0.1:33595/oidc2241--- PASS: TestValidateToken_MultipleProviders (0.30s)2242PASS2243Running hook tests...2244=== RUN TestSendPathsEmpty2245=== PAUSE TestSendPathsEmpty2246=== RUN TestQueueEnqueueAndFetch2247=== PAUSE TestQueueEnqueueAndFetch2248=== RUN TestQueueDeduplication2249=== PAUSE TestQueueDeduplication2250=== RUN TestQueueRemove2251=== PAUSE TestQueueRemove2252=== RUN TestQueueFetchBatchLimit2253=== PAUSE TestQueueFetchBatchLimit2254=== RUN TestQueueRetryMovesToBack2255=== PAUSE TestQueueRetryMovesToBack2256=== RUN TestQueueFetchRemoveLifecycle2257=== PAUSE TestQueueFetchRemoveLifecycle2258=== RUN TestQueueConcurrentWriters2259=== PAUSE TestQueueConcurrentWriters2260=== RUN TestQueueRemoveLargeClosure2261=== PAUSE TestQueueRemoveLargeClosure2262=== RUN TestServerClientIntegration2263=== PAUSE TestServerClientIntegration2264=== RUN TestServerQueueError2265=== PAUSE TestServerQueueError2266=== RUN TestGetListenerSocketActivation2267 server_test.go:210: === RUN TestGetListenerSocketActivation2268 --- PASS: TestGetListenerSocketActivation (0.00s)2269 PASS2270 2271--- PASS: TestGetListenerSocketActivation (0.01s)2272=== RUN TestDrainIsolatesPoisonPath2273=== PAUSE TestDrainIsolatesPoisonPath2274=== RUN TestRunNotBlockedByPoisonHead2275=== PAUSE TestRunNotBlockedByPoisonHead2276=== RUN TestDrainGivesUpWhenServerDown2277=== PAUSE TestDrainGivesUpWhenServerDown2278=== RUN TestFailedPathPrunedByLaterClosure2279=== PAUSE TestFailedPathPrunedByLaterClosure2280=== RUN TestWorkerUploadsAndRemoves2281=== PAUSE TestWorkerUploadsAndRemoves2282=== RUN TestWorkerSkipsGCdPaths2283=== PAUSE TestWorkerSkipsGCdPaths2284=== RUN TestWorkerPrunesClosureDeps2285=== PAUSE TestWorkerPrunesClosureDeps2286=== RUN TestDrainTimeout2287=== PAUSE TestDrainTimeout2288=== CONT TestSendPathsEmpty2289=== CONT TestDrainGivesUpWhenServerDown2290=== CONT TestQueueDeduplication2291--- PASS: TestSendPathsEmpty (0.00s)2292=== CONT TestWorkerSkipsGCdPaths2293=== CONT TestRunNotBlockedByPoisonHead2294=== CONT TestDrainIsolatesPoisonPath2295=== CONT TestDrainTimeout2296=== CONT TestServerQueueError2297=== CONT TestServerClientIntegration2298=== CONT TestWorkerPrunesClosureDeps2299=== CONT TestQueueRemoveLargeClosure2300=== CONT TestQueueConcurrentWriters2301=== CONT TestQueueFetchBatchLimit2302=== CONT TestQueueEnqueueAndFetch23032026/10/06 08:16:21 ERROR Failed to queue paths error="permission denied" count=12304=== CONT TestQueueFetchRemoveLifecycle2305=== CONT TestQueueRetryMovesToBack2306=== CONT TestWorkerUploadsAndRemoves2307=== CONT TestQueueRemove2308=== CONT TestFailedPathPrunedByLaterClosure2309--- PASS: TestServerQueueError (0.00s)2310--- PASS: TestServerClientIntegration (0.00s)23112026/10/06 08:16:21 INFO Upload queue status pending=223122026/10/06 08:16:21 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths536247461/002/nonexistent23132026/10/06 08:16:21 INFO Upload queue status pending=323142026/10/06 08:16:21 INFO Uploading batch count=123152026/10/06 08:16:21 INFO Uploading batch count=123162026/10/06 08:16:21 ERROR Upload failed error="upload failed" count=123172026/10/06 08:16:21 INFO Uploading batch count=123182026/10/06 08:16:21 INFO Uploading batch count=423192026/10/06 08:16:21 ERROR Upload failed error="upload failed" count=123202026/10/06 08:16:21 ERROR Upload failed error="upload failed" count=42321--- PASS: TestQueueEnqueueAndFetch (0.01s)23222026/10/06 08:16:21 INFO Uploading batch count=223232026/10/06 08:16:21 INFO Uploading batch count=223242026/10/06 08:16:21 ERROR Upload failed error="upload failed" count=223252026/10/06 08:16:21 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown507362075/002/a2326--- PASS: TestQueueRemove (0.01s)23272026/10/06 08:16:21 INFO Uploading batch count=123282026/10/06 08:16:21 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainIsolatesPoisonPath266882971/002/bbb23292026/10/06 08:16:21 INFO Upload queue status pending=223302026/10/06 08:16:21 INFO Uploading batch count=12331--- PASS: TestQueueFetchRemoveLifecycle (0.01s)23322026/10/06 08:16:21 INFO Upload queue status pending=22333--- PASS: TestQueueFetchBatchLimit (0.02s)23342026/10/06 08:16:21 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown507362075/002/b2335--- PASS: TestQueueRetryMovesToBack (0.02s)23362026/10/06 08:16:21 INFO Uploading batch count=22337--- PASS: TestQueueDeduplication (0.02s)23382026/10/06 08:16:21 INFO Uploading batch count=123392026/10/06 08:16:21 INFO Uploading batch count=223402026/10/06 08:16:21 ERROR Upload failed error="upload failed" count=223412026/10/06 08:16:21 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown507362075/002/c23422026/10/06 08:16:21 INFO Uploading batch count=123432026/10/06 08:16:21 ERROR Upload failed error="upload failed" count=123442026/10/06 08:16:21 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown507362075/002/d23452026/10/06 08:16:21 INFO Uploading batch count=123462026/10/06 08:16:21 ERROR Upload failed error="upload failed" count=123472026/10/06 08:16:21 INFO Uploading batch count=223482026/10/06 08:16:21 ERROR Upload failed error="upload failed" count=223492026/10/06 08:16:21 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown507362075/002/e23502026/10/06 08:16:21 INFO Uploading batch count=123512026/10/06 08:16:21 ERROR Upload failed error="upload failed" count=12352--- PASS: TestFailedPathPrunedByLaterClosure (0.02s)23532026/10/06 08:16:21 ERROR Drain finished with paths left in queue remaining=123542026/10/06 08:16:21 ERROR Upload failed, will retry later error="upload failed" path=/build/TestDrainGivesUpWhenServerDown507362075/002/f23552026/10/06 08:16:21 ERROR Drain finished with paths left in queue remaining=102356--- PASS: TestDrainIsolatesPoisonPath (0.02s)2357--- PASS: TestDrainGivesUpWhenServerDown (0.02s)2358--- PASS: TestWorkerSkipsGCdPaths (0.03s)2359--- PASS: TestWorkerUploadsAndRemoves (0.03s)2360--- PASS: TestWorkerPrunesClosureDeps (0.04s)2361--- PASS: TestQueueRemoveLargeClosure (0.11s)2362--- PASS: TestQueueConcurrentWriters (0.13s)23632026/10/06 08:16:21 ERROR Upload failed error="context deadline exceeded" count=223642026/10/06 08:16:21 ERROR Drain finished with paths left in queue remaining=42365--- PASS: TestDrainTimeout (0.22s)23662026/10/06 08:16:22 INFO Uploading batch count=123672026/10/06 08:16:22 INFO Uploading batch count=123682026/10/06 08:16:22 INFO Uploading batch count=123692026/10/06 08:16:22 ERROR Upload failed error="upload failed" count=123702026/10/06 08:16:22 INFO Uploading batch count=123712026/10/06 08:16:22 ERROR Upload failed error="upload failed" count=123722026/10/06 08:16:22 INFO Uploading batch count=123732026/10/06 08:16:22 ERROR Upload failed error="upload failed" count=123742026/10/06 08:16:22 INFO Uploading batch count=123752026/10/06 08:16:22 ERROR Upload failed error="upload failed" count=123762026/10/06 08:16:22 ERROR Drain finished with paths left in queue remaining=12377--- PASS: TestRunNotBlockedByPoisonHead (1.03s)2378PASS2379Running signing tests...2380=== RUN TestExternalSigner2381=== PAUSE TestExternalSigner2382=== RUN TestExternalSignerWrongPublicKey2383=== PAUSE TestExternalSignerWrongPublicKey2384=== RUN TestExternalSignerErrors2385=== PAUSE TestExternalSignerErrors2386=== RUN TestExternalSignerRestartsAfterCrash2387=== PAUSE TestExternalSignerRestartsAfterCrash2388=== RUN TestExternalSignerProgramErrorKeepsProcess2389=== PAUSE TestExternalSignerProgramErrorKeepsProcess2390=== RUN TestExternalSignerClose2391=== PAUSE TestExternalSignerClose2392=== RUN TestExternalSignerRejectsInvalidPublicKey2393=== PAUSE TestExternalSignerRejectsInvalidPublicKey2394=== RUN TestExternalSignerCancellation2395=== PAUSE TestExternalSignerCancellation2396=== RUN TestGenerateFingerprint2397=== PAUSE TestGenerateFingerprint2398=== RUN TestParseSigningKey2399=== PAUSE TestParseSigningKey2400=== RUN TestSign2401=== PAUSE TestSign2402=== RUN TestSignBatch2403=== PAUSE TestSignBatch2404=== RUN TestNilKeyPublicKey2405=== PAUSE TestNilKeyPublicKey2406=== RUN TestSignErrors2407=== PAUSE TestSignErrors2408=== CONT TestExternalSigner2409=== CONT TestExternalSignerCancellation2410=== CONT TestSignBatch2411=== CONT TestParseSigningKey2412=== RUN TestParseSigningKey/valid_32-byte_key2413=== PAUSE TestParseSigningKey/valid_32-byte_key2414=== RUN TestParseSigningKey/valid_32-byte_key_with_different_name2415=== PAUSE TestParseSigningKey/valid_32-byte_key_with_different_name2416=== CONT TestSignErrors2417=== CONT TestNilKeyPublicKey2418--- PASS: TestNilKeyPublicKey (0.00s)2419=== CONT TestGenerateFingerprint2420=== RUN TestGenerateFingerprint/basic_with_references2421=== CONT TestExternalSignerProgramErrorKeepsProcess2422=== PAUSE TestGenerateFingerprint/basic_with_references2423=== RUN TestGenerateFingerprint/no_references2424=== PAUSE TestGenerateFingerprint/no_references2425=== CONT TestExternalSignerRejectsInvalidPublicKey2426=== CONT TestExternalSignerClose2427=== CONT TestExternalSignerErrors2428=== CONT TestExternalSignerRestartsAfterCrash2429=== CONT TestExternalSignerWrongPublicKey2430=== CONT TestSign2431=== RUN TestParseSigningKey/no_colon2432=== PAUSE TestParseSigningKey/no_colon2433=== RUN TestGenerateFingerprint/unsorted_references_get_sorted2434=== PAUSE TestGenerateFingerprint/unsorted_references_get_sorted2435=== RUN TestGenerateFingerprint/invalid_nar_hash_prefix2436=== PAUSE TestGenerateFingerprint/invalid_nar_hash_prefix2437=== RUN TestGenerateFingerprint/invalid_nar_hash_length2438=== PAUSE TestGenerateFingerprint/invalid_nar_hash_length2439=== RUN TestGenerateFingerprint/invalid_store_path_prefix2440=== PAUSE TestGenerateFingerprint/invalid_store_path_prefix2441=== RUN TestParseSigningKey/empty_name2442=== RUN TestExternalSignerErrors/wrong_id2443=== RUN TestGenerateFingerprint/invalid_reference_prefix2444=== RUN TestExternalSignerRejectsInvalidPublicKey/#002445=== PAUSE TestExternalSignerRejectsInvalidPublicKey/#002446--- PASS: TestSign (0.00s)2447=== PAUSE TestParseSigningKey/empty_name2448=== PAUSE TestExternalSignerErrors/wrong_id2449=== RUN TestSignErrors/nil_key2450=== PAUSE TestGenerateFingerprint/invalid_reference_prefix2451=== CONT TestGenerateFingerprint/basic_with_references2452=== CONT TestGenerateFingerprint/invalid_reference_prefix2453--- PASS: TestSignBatch (0.00s)2454=== RUN TestExternalSignerRejectsInvalidPublicKey/no-colon2455=== RUN TestParseSigningKey/invalid_base642456=== PAUSE TestSignErrors/nil_key2457=== RUN TestExternalSignerErrors/missing_signatures2458=== CONT TestGenerateFingerprint/invalid_nar_hash_length2459=== CONT TestGenerateFingerprint/invalid_store_path_prefix2460=== CONT TestGenerateFingerprint/unsorted_references_get_sorted2461=== CONT TestGenerateFingerprint/invalid_nar_hash_prefix2462=== CONT TestGenerateFingerprint/no_references2463--- PASS: TestGenerateFingerprint (0.00s)2464 --- PASS: TestGenerateFingerprint/basic_with_references (0.00s)2465 --- PASS: TestGenerateFingerprint/invalid_reference_prefix (0.00s)2466 --- PASS: TestGenerateFingerprint/invalid_nar_hash_length (0.00s)2467 --- PASS: TestGenerateFingerprint/invalid_store_path_prefix (0.00s)2468 --- PASS: TestGenerateFingerprint/unsorted_references_get_sorted (0.00s)2469 --- PASS: TestGenerateFingerprint/invalid_nar_hash_prefix (0.00s)2470 --- PASS: TestGenerateFingerprint/no_references (0.00s)2471=== PAUSE TestExternalSignerRejectsInvalidPublicKey/no-colon2472=== RUN TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=2473=== PAUSE TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=2474=== RUN TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!2475=== PAUSE TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!2476=== RUN TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==2477=== PAUSE TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==2478=== CONT TestExternalSignerRejectsInvalidPublicKey/#002479=== CONT TestExternalSignerRejectsInvalidPublicKey/no-colon2480=== PAUSE TestParseSigningKey/invalid_base642481=== RUN TestParseSigningKey/wrong_length2482=== PAUSE TestParseSigningKey/wrong_length2483=== CONT TestParseSigningKey/valid_32-byte_key2484=== CONT TestParseSigningKey/no_colon2485=== CONT TestExternalSignerRejectsInvalidPublicKey/test-key:invalid!2486=== CONT TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA=2487=== RUN TestSignErrors/nil_narinfo2488=== PAUSE TestSignErrors/nil_narinfo2489=== PAUSE TestExternalSignerErrors/missing_signatures2490=== CONT TestExternalSignerRejectsInvalidPublicKey/test-key:YQ==2491--- PASS: TestExternalSignerRejectsInvalidPublicKey (0.00s)2492 --- PASS: TestExternalSignerRejectsInvalidPublicKey/#00 (0.00s)2493 --- PASS: TestExternalSignerRejectsInvalidPublicKey/no-colon (0.00s)2494 --- PASS: TestExternalSignerRejectsInvalidPublicKey/test-key:invalid! (0.00s)2495 --- PASS: TestExternalSignerRejectsInvalidPublicKey/:AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA= (0.00s)2496 --- PASS: TestExternalSignerRejectsInvalidPublicKey/test-key:YQ== (0.00s)2497=== CONT TestParseSigningKey/empty_name2498=== CONT TestParseSigningKey/wrong_length2499=== CONT TestParseSigningKey/invalid_base642500=== CONT TestParseSigningKey/valid_32-byte_key_with_different_name2501--- PASS: TestParseSigningKey (0.00s)2502 --- PASS: TestParseSigningKey/no_colon (0.00s)2503 --- PASS: TestParseSigningKey/valid_32-byte_key (0.00s)2504 --- PASS: TestParseSigningKey/empty_name (0.00s)2505 --- PASS: TestParseSigningKey/wrong_length (0.00s)2506 --- PASS: TestParseSigningKey/invalid_base64 (0.00s)2507 --- PASS: TestParseSigningKey/valid_32-byte_key_with_different_name (0.00s)2508=== RUN TestSignErrors/invalid_narinfo2509=== PAUSE TestSignErrors/invalid_narinfo2510=== RUN TestSignErrors/canceled2511=== PAUSE TestSignErrors/canceled2512=== CONT TestSignErrors/nil_key2513=== CONT TestSignErrors/invalid_narinfo2514=== RUN TestExternalSignerErrors/wrong_signatures_shape2515=== PAUSE TestExternalSignerErrors/wrong_signatures_shape2516=== RUN TestExternalSignerErrors/unrequested_key2517=== CONT TestSignErrors/canceled2518=== CONT TestSignErrors/nil_narinfo2519--- PASS: TestSignErrors (0.00s)2520 --- PASS: TestSignErrors/nil_key (0.00s)2521 --- PASS: TestSignErrors/invalid_narinfo (0.00s)2522 --- PASS: TestSignErrors/canceled (0.00s)2523 --- PASS: TestSignErrors/nil_narinfo (0.00s)2524=== PAUSE TestExternalSignerErrors/unrequested_key2525=== RUN TestExternalSignerErrors/wrong_signature_name2526=== PAUSE TestExternalSignerErrors/wrong_signature_name2527=== RUN TestExternalSignerErrors/too_few_signatures2528=== PAUSE TestExternalSignerErrors/too_few_signatures2529=== RUN TestExternalSignerErrors/wrong_signature_length2530=== PAUSE TestExternalSignerErrors/wrong_signature_length2531=== RUN TestExternalSignerErrors/exit_on_request2532=== PAUSE TestExternalSignerErrors/exit_on_request2533=== RUN TestExternalSignerErrors/invalid_JSON2534=== PAUSE TestExternalSignerErrors/invalid_JSON2535=== RUN TestExternalSignerErrors/null2536=== PAUSE TestExternalSignerErrors/null2537=== RUN TestExternalSignerErrors/null_signatures2538=== PAUSE TestExternalSignerErrors/null_signatures2539=== RUN TestExternalSignerErrors/missing_signature_name2540=== PAUSE TestExternalSignerErrors/missing_signature_name2541=== RUN TestExternalSignerErrors/invalid_signature_base642542--- PASS: TestExternalSignerProgramErrorKeepsProcess (0.00s)2543=== PAUSE TestExternalSignerErrors/invalid_signature_base642544=== RUN TestExternalSignerErrors/program_error2545=== PAUSE TestExternalSignerErrors/program_error2546=== RUN TestExternalSignerErrors/exit_at_startup2547=== PAUSE TestExternalSignerErrors/exit_at_startup2548=== RUN TestExternalSignerErrors/wrong_shape2549=== PAUSE TestExternalSignerErrors/wrong_shape2550=== CONT TestExternalSignerErrors/wrong_shape2551=== CONT TestExternalSignerErrors/wrong_id2552=== CONT TestExternalSignerErrors/exit_on_request2553=== CONT TestExternalSignerErrors/wrong_signature_length2554=== CONT TestExternalSignerErrors/invalid_signature_base642555=== CONT TestExternalSignerErrors/too_few_signatures2556=== CONT TestExternalSignerErrors/exit_at_startup2557=== CONT TestExternalSignerErrors/wrong_signature_name2558=== CONT TestExternalSignerErrors/program_error2559=== CONT TestExternalSignerErrors/invalid_JSON2560=== CONT TestExternalSignerErrors/unrequested_key2561=== CONT TestExternalSignerErrors/missing_signature_name2562=== CONT TestExternalSignerErrors/wrong_signatures_shape2563=== CONT TestExternalSignerErrors/missing_signatures2564=== CONT TestExternalSignerErrors/null_signatures2565=== CONT TestExternalSignerErrors/null2566--- PASS: TestExternalSignerWrongPublicKey (0.00s)2567--- PASS: TestExternalSignerClose (0.00s)2568--- PASS: TestExternalSigner (0.01s)2569--- PASS: TestExternalSignerRestartsAfterCrash (0.01s)2570--- PASS: TestExternalSignerErrors (0.00s)2571 --- PASS: TestExternalSignerErrors/too_few_signatures (0.00s)2572 --- PASS: TestExternalSignerErrors/wrong_signature_length (0.00s)2573 --- PASS: TestExternalSignerErrors/invalid_JSON (0.00s)2574 --- PASS: TestExternalSignerErrors/wrong_id (0.00s)2575 --- PASS: TestExternalSignerErrors/program_error (0.00s)2576 --- PASS: TestExternalSignerErrors/exit_on_request (0.00s)2577 --- PASS: TestExternalSignerErrors/exit_at_startup (0.00s)2578 --- PASS: TestExternalSignerErrors/wrong_signature_name (0.00s)2579 --- PASS: TestExternalSignerErrors/invalid_signature_base64 (0.00s)2580 --- PASS: TestExternalSignerErrors/null_signatures (0.00s)2581 --- PASS: TestExternalSignerErrors/wrong_shape (0.01s)2582 --- PASS: TestExternalSignerErrors/unrequested_key (0.00s)2583 --- PASS: TestExternalSignerErrors/missing_signature_name (0.00s)2584 --- PASS: TestExternalSignerErrors/wrong_signatures_shape (0.00s)2585 --- PASS: TestExternalSignerErrors/missing_signatures (0.00s)2586 --- PASS: TestExternalSignerErrors/null (0.00s)2587--- PASS: TestExternalSignerCancellation (0.02s)2588PASS2589Running OpenBao signer tests...2590=== RUN TestSign2591=== PAUSE TestSign2592=== RUN TestSignErrors2593=== PAUSE TestSignErrors2594=== RUN TestServe2595=== PAUSE TestServe2596=== RUN TestServeRejectsGarbage2597=== PAUSE TestServeRejectsGarbage2598=== CONT TestSign2599=== CONT TestServe2600=== CONT TestServeRejectsGarbage2601=== CONT TestSignErrors2602--- PASS: TestServeRejectsGarbage (0.00s)2603=== RUN TestSignErrors/http_error2604--- PASS: TestServe (0.00s)2605=== PAUSE TestSignErrors/http_error2606=== RUN TestSignErrors/invalid_json2607=== PAUSE TestSignErrors/invalid_json2608=== RUN TestSignErrors/no_prefix2609=== PAUSE TestSignErrors/no_prefix2610=== RUN TestSignErrors/invalid_base642611=== PAUSE TestSignErrors/invalid_base642612=== CONT TestSignErrors/http_error2613=== CONT TestSignErrors/no_prefix2614=== CONT TestSignErrors/invalid_json2615=== CONT TestSignErrors/invalid_base642616--- PASS: TestSign (0.00s)2617--- PASS: TestSignErrors (0.00s)2618 --- PASS: TestSignErrors/invalid_json (0.00s)2619 --- PASS: TestSignErrors/http_error (0.00s)2620 --- PASS: TestSignErrors/invalid_base64 (0.00s)2621 --- PASS: TestSignErrors/no_prefix (0.00s)2622PASS