$ /nix/store/9ypz3flqsrl5xl495mm8h645gadjsxi1-coreutils-9.11/bin/timeout --kill-after=15s 7200s /nix/store/23zk8sgzamrbmj1fday15szf7f2279s8-nix-2.34.7/bin/nix --extra-experimental-features nix-command --extra-experimental-features flakes --log-format internal-json build --no-link --print-out-paths git+https://github.com/NorfairKing/niks3?ref=main&rev=b306808bf381e7e66e33de1e9446a1be0935f4e3#checks.x86_64-linux.go-unit-tests --print-build-logs
error (ignored): SQLite database '/var/cache/nix-ci-worker/eval-cache-v6/a9373adb9c2e09bf6e6b0b8433c473aa95aed607f8fa6c26078f8d3d37d52fa6.sqlite' is busy

Downloading cached postgresql-18.4-doc from https://cache.staging.nix-ci.com
Downloading cached niks3-tests from https://cache.staging.nix-ci.com
Downloading cached nlohmann_json from https://cache.staging.nix-ci.com
Downloading cached brotli from https://cache.staging.nix-ci.com
Downloading cached libpsl-0.21.5-dev from https://cache.staging.nix-ci.com
Downloading cached libssh2-1.11.1-dev from https://cache.staging.nix-ci.com
Downloading cached nghttp3-1.16.0-dev from https://cache.staging.nix-ci.com
Downloading cached ngtcp2-1.23.0-dev from https://cache.staging.nix-ci.com
Downloading cached nix from https://cache.staging.nix-ci.com
Downloading cached nix-perl from https://cache.staging.nix-ci.com
Downloading cached numactl-2.0.18-dev from https://cache.staging.nix-ci.com
Downloading cached boehm-gc-8.2.12-dev from https://cache.staging.nix-ci.com
Downloading cached boost-1.89.0-dev from https://cache.staging.nix-ci.com
Downloading cached c-ares from https://cache.staging.nix-ci.com
Downloading cached libidn2-2.3.8-bin from https://cache.staging.nix-ci.com
Downloading cached liburing-2.14-bin from https://cache.staging.nix-ci.com
Downloaded cached brotli (53 KiB) in 98ms
Progress: 0 of 4 built, 1 of 48 downloaded from cache (15 downloading)
Downloaded cached libssh2-1.11.1-dev (83 KiB) in 98ms
Downloading cached acl-2.3.2-dev from https://cache.staging.nix-ci.com
Downloaded cached nghttp3-1.16.0-dev (123 KiB) in 98ms
Progress: 0 of 4 built, 2 of 48 downloaded from cache (15 downloading)
Progress: 0 of 4 built, 3 of 48 downloaded from cache (14 downloading)
Downloading cached attr-2.5.2-dev from https://cache.staging.nix-ci.com
Downloading cached brotli-1.2.0-dev from https://cache.staging.nix-ci.com
Downloaded cached ngtcp2-1.23.0-dev (302 KiB) in 101ms
Progress: 0 of 4 built, 4 of 48 downloaded from cache (15 downloading)
Downloading cached libarchive from https://cache.staging.nix-ci.com
Downloaded cached nlohmann_json (970 KiB) in 160ms
Progress: 0 of 4 built, 5 of 48 downloaded from cache (15 downloading)
Downloading cached libev from https://cache.staging.nix-ci.com
Downloaded cached libpsl-0.21.5-dev (7800 B) in 165ms
Progress: 0 of 4 built, 6 of 48 downloaded from cache (15 downloading)
Downloading cached nix-util-c from https://cache.staging.nix-ci.com
Downloaded cached nix (3.9 MiB) in 166ms
Progress: 0 of 4 built, 7 of 48 downloaded from cache (15 downloading)
Downloaded cached nix-perl (211 KiB) in 177ms
Progress: 0 of 4 built, 8 of 48 downloaded from cache (14 downloading)
Downloaded cached c-ares (276 KiB) in 197ms
Progress: 0 of 4 built, 9 of 48 downloaded from cache (13 downloading)
Downloaded cached libidn2-2.3.8-bin (37 KiB) in 198ms
Progress: 0 of 4 built, 10 of 48 downloaded from cache (12 downloading)
Downloaded cached attr-2.5.2-dev (13 KiB) in 99ms
Downloading cached libidn2-2.3.8-dev from https://cache.staging.nix-ci.com
Downloaded cached acl-2.3.2-dev (8624 B) in 100ms
Progress: 0 of 4 built, 11 of 48 downloaded from cache (12 downloading)
Progress: 0 of 4 built, 12 of 48 downloaded from cache (11 downloading)
Downloaded cached brotli-1.2.0-dev (57 KiB) in 100ms
Progress: 0 of 4 built, 13 of 48 downloaded from cache (10 downloading)
Downloaded cached boehm-gc-8.2.12-dev (256 KiB) in 199ms
Progress: 0 of 4 built, 14 of 48 downloaded from cache (9 downloading)
Downloaded cached liburing-2.14-bin (640 KiB) in 126ms
Progress: 0 of 4 built, 15 of 48 downloaded from cache (8 downloading)
Downloading cached liburing-2.14-dev from https://cache.staging.nix-ci.com
Downloaded cached libarchive (348 KiB) in 101ms
Progress: 0 of 4 built, 16 of 48 downloaded from cache (8 downloading)
Downloading cached libarchive-3.8.8-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-util-c (106 KiB) in 54ms
Progress: 0 of 4 built, 17 of 48 downloaded from cache (8 downloading)
Downloading cached nix-fetchers-c from https://cache.staging.nix-ci.com
Downloading cached nix-main-c from https://cache.staging.nix-ci.com
Downloading cached nix-store-c from https://cache.staging.nix-ci.com
Downloaded cached libev (225 KiB) in 75ms
Progress: 0 of 4 built, 18 of 48 downloaded from cache (10 downloading)
Downloading cached nghttp2 from https://cache.staging.nix-ci.com
Downloaded cached libidn2-2.3.8-dev (15 KiB) in 69ms
Progress: 0 of 4 built, 19 of 48 downloaded from cache (10 downloading)
Downloaded cached liburing-2.14-dev (95 KiB) in 69ms
Progress: 0 of 4 built, 20 of 48 downloaded from cache (9 downloading)
Downloaded cached nix-fetchers-c (66 KiB) in 67ms
Downloaded cached libarchive-3.8.8-dev (91 KiB) in 84ms
Progress: 0 of 4 built, 21 of 48 downloaded from cache (8 downloading)
Progress: 0 of 4 built, 22 of 48 downloaded from cache (7 downloading)
Downloaded cached nix-main-c (16 KiB) in 68ms
Progress: 0 of 4 built, 23 of 48 downloaded from cache (6 downloading)
Downloaded cached nix-store-c (246 KiB) in 68ms
Progress: 0 of 4 built, 24 of 48 downloaded from cache (5 downloading)
Downloading cached nix-expr-c from https://cache.staging.nix-ci.com
Downloaded cached postgresql-18.4-doc (16.5 MiB) in 293ms
Progress: 0 of 4 built, 25 of 48 downloaded from cache (5 downloading)
Downloaded cached nghttp2 (2.6 MiB) in 94ms
Progress: 0 of 4 built, 26 of 48 downloaded from cache (4 downloading)
Downloading cached nghttp2-1.69.0-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-expr-c (402 KiB) in 49ms
Progress: 0 of 4 built, 27 of 48 downloaded from cache (4 downloading)
Downloading cached nix-flake-c from https://cache.staging.nix-ci.com
Downloaded cached nghttp2-1.69.0-dev (241 KiB) in 32ms
Progress: 0 of 4 built, 28 of 48 downloaded from cache (4 downloading)
Downloading cached curl-8.21.0-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-flake-c (146 KiB) in 32ms
Progress: 0 of 4 built, 29 of 48 downloaded from cache (4 downloading)
Downloaded cached curl-8.21.0-dev (275 KiB) in 34ms
Progress: 0 of 4 built, 30 of 48 downloaded from cache (3 downloading)
Downloaded cached niks3-tests (48.1 MiB) in 586ms
Progress: 0 of 4 built, 31 of 48 downloaded from cache (2 downloading)
Downloaded cached numactl-2.0.18-dev (19 KiB) in 603ms
Progress: 0 of 3 built, 32 of 48 downloaded from cache (1 downloading)
Downloading cached postgresql-18.4-dev from https://cache.staging.nix-ci.com
Downloaded cached postgresql-18.4-dev (11.4 MiB) in 136ms
Progress: 0 of 3 built, 33 of 48 downloaded from cache (1 downloading)
Downloaded cached boost-1.89.0-dev (147.1 MiB) in 1.2s
Progress: 0 of 2 built, 34 of 48 downloaded from cache
Downloading cached nix-util-2.34.8-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-util-2.34.8-dev (342 KiB) in 43ms
Progress: 0 of 2 built, 35 of 48 downloaded from cache
Downloading cached nix-store-2.34.8-dev from https://cache.staging.nix-ci.com
Downloading cached nix-util-c-2.34.8-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-util-c-2.34.8-dev (15 KiB) in 32ms
Progress: 0 of 2 built, 36 of 48 downloaded from cache (1 downloading)
Downloaded cached nix-store-2.34.8-dev (425 KiB) in 39ms
Progress: 0 of 2 built, 37 of 48 downloaded from cache
Downloading cached nix-fetchers-2.34.8-dev from https://cache.staging.nix-ci.com
Downloading cached nix-store-c-2.34.8-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-fetchers-2.34.8-dev (34 KiB) in 32ms
Progress: 0 of 2 built, 38 of 48 downloaded from cache (1 downloading)
Downloaded cached nix-store-c-2.34.8-dev (17 KiB) in 32ms
Progress: 0 of 2 built, 39 of 48 downloaded from cache (1 downloading)
Downloading cached nix-expr-2.34.8-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-expr-2.34.8-dev (739 KiB) in 34ms
Progress: 0 of 2 built, 40 of 48 downloaded from cache
Downloading cached nix-expr-c-2.34.8-dev from https://cache.staging.nix-ci.com
Downloading cached nix-flake-2.34.8-dev from https://cache.staging.nix-ci.com
Downloading cached nix-main-2.34.8-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-expr-c-2.34.8-dev (50 KiB) in 32ms
Progress: 0 of 2 built, 41 of 48 downloaded from cache (2 downloading)
Downloading cached nix-fetchers-c-2.34.8-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-main-2.34.8-dev (10 KiB) in 34ms
Progress: 0 of 2 built, 42 of 48 downloaded from cache (2 downloading)
Downloading cached nix-main-c-2.34.8-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-flake-2.34.8-dev (19 KiB) in 37ms
Progress: 0 of 2 built, 43 of 48 downloaded from cache (2 downloading)
Downloading cached nix-cmd-2.34.8-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-main-c-2.34.8-dev (3136 B) in 29ms
Progress: 0 of 2 built, 44 of 48 downloaded from cache (2 downloading)
Downloaded cached nix-cmd-2.34.8-dev (44 KiB) in 30ms
Progress: 0 of 2 built, 45 of 48 downloaded from cache (1 downloading)
Downloaded cached nix-fetchers-c-2.34.8-dev (3264 B) in 128ms
Progress: 0 of 2 built, 46 of 48 downloaded from cache
Downloading cached nix-flake-c-2.34.8-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-flake-c-2.34.8-dev (11 KiB) in 36ms
Progress: 0 of 2 built, 47 of 48 downloaded from cache
Downloading cached nix-2.34.8-dev from https://cache.staging.nix-ci.com
Downloaded cached nix-2.34.8-dev (91 KiB) in 38ms
Progress: 0 of 2 built, 48 of 48 downloaded from cache
Building /nix/store/pjvglmgx4k8v1fgng4daqcmi9q9hf5f5-niks3-go-unit-tests.drv
[niks3-go-unit-tests] Running client tests...
[niks3-go-unit-tests] === RUN   TestDoServerRequestAttachesToken
[niks3-go-unit-tests] === PAUSE TestDoServerRequestAttachesToken
[niks3-go-unit-tests] === RUN   TestCaseHackSuffix
[niks3-go-unit-tests] === PAUSE TestCaseHackSuffix
[niks3-go-unit-tests] === RUN   TestPartSizeForNAR
[niks3-go-unit-tests] === PAUSE TestPartSizeForNAR
[niks3-go-unit-tests] === RUN   TestUploadMultipart_SupersededByPeer
[niks3-go-unit-tests] === PAUSE TestUploadMultipart_SupersededByPeer
[niks3-go-unit-tests] === RUN   TestDumpPathMatchesNix
[niks3-go-unit-tests] === PAUSE TestDumpPathMatchesNix
[niks3-go-unit-tests] === RUN   TestDumpPathSingleFile
[niks3-go-unit-tests] === PAUSE TestDumpPathSingleFile
[niks3-go-unit-tests] === RUN   TestDumpPathWriterError
[niks3-go-unit-tests] === PAUSE TestDumpPathWriterError
[niks3-go-unit-tests] === RUN   TestEncodeNixBase32
[niks3-go-unit-tests] === PAUSE TestEncodeNixBase32
[niks3-go-unit-tests] === RUN   TestEncodeNixBase32WithRealHash
[niks3-go-unit-tests] === PAUSE TestEncodeNixBase32WithRealHash
[niks3-go-unit-tests] === RUN   TestConvertHashToNix32
[niks3-go-unit-tests] === PAUSE TestConvertHashToNix32
[niks3-go-unit-tests] === RUN   TestGetStorePathHash
[niks3-go-unit-tests] === PAUSE TestGetStorePathHash
[niks3-go-unit-tests] === RUN   TestPathInfoHashCompatibility
[niks3-go-unit-tests] === PAUSE TestPathInfoHashCompatibility
[niks3-go-unit-tests] === RUN   TestParsePathInfoJSON
[niks3-go-unit-tests] === PAUSE TestParsePathInfoJSON
[niks3-go-unit-tests] === RUN   TestParsePathInfoJSONMultiplePaths
[niks3-go-unit-tests] === PAUSE TestParsePathInfoJSONMultiplePaths
[niks3-go-unit-tests] === RUN   TestPathInfoCACompatibility
[niks3-go-unit-tests] === PAUSE TestPathInfoCACompatibility
[niks3-go-unit-tests] === RUN   TestRateLimiterFeedback
[niks3-go-unit-tests] === PAUSE TestRateLimiterFeedback
[niks3-go-unit-tests] === RUN   TestRateLimiterFeedback_400DoesNotCountAsSuccess
[niks3-go-unit-tests] === PAUSE TestRateLimiterFeedback_400DoesNotCountAsSuccess
[niks3-go-unit-tests] === RUN   TestResolveStorePath
[niks3-go-unit-tests] === PAUSE TestResolveStorePath
[niks3-go-unit-tests] === RUN   TestDoWithRetry_BodyReplayedViaGetBody
[niks3-go-unit-tests] === PAUSE TestDoWithRetry_BodyReplayedViaGetBody
[niks3-go-unit-tests] === RUN   TestShellSplit
[niks3-go-unit-tests] === PAUSE TestShellSplit
[niks3-go-unit-tests] === RUN   TestShellSplitErrors
[niks3-go-unit-tests] === PAUSE TestShellSplitErrors
[niks3-go-unit-tests] === RUN   TestSetClientTLS
[niks3-go-unit-tests] === PAUSE TestSetClientTLS
[niks3-go-unit-tests] === RUN   TestSetClientTLSDoesNotMutateDefaultTransport
[niks3-go-unit-tests] === PAUSE TestSetClientTLSDoesNotMutateDefaultTransport
[niks3-go-unit-tests] === RUN   TestSetClientTLSErrors
[niks3-go-unit-tests] === PAUSE TestSetClientTLSErrors
[niks3-go-unit-tests] === RUN   TestStaticToken
[niks3-go-unit-tests] === PAUSE TestStaticToken
[niks3-go-unit-tests] === RUN   TestFileTokenReadsAndCaches
[niks3-go-unit-tests] === PAUSE TestFileTokenReadsAndCaches
[niks3-go-unit-tests] === RUN   TestFileTokenMissing
[niks3-go-unit-tests] === PAUSE TestFileTokenMissing
[niks3-go-unit-tests] === RUN   TestFileTokenEmpty
[niks3-go-unit-tests] === PAUSE TestFileTokenEmpty
[niks3-go-unit-tests] === RUN   TestScriptTokenNoExpiryRerunsEveryCall
[niks3-go-unit-tests] === PAUSE TestScriptTokenNoExpiryRerunsEveryCall
[niks3-go-unit-tests] === RUN   TestScriptTokenCachesUntilRefresh
[niks3-go-unit-tests] === PAUSE TestScriptTokenCachesUntilRefresh
[niks3-go-unit-tests] === RUN   TestScriptTokenEmptyToken
[niks3-go-unit-tests] === PAUSE TestScriptTokenEmptyToken
[niks3-go-unit-tests] === RUN   TestScriptTokenBadJSON
[niks3-go-unit-tests] === PAUSE TestScriptTokenBadJSON
[niks3-go-unit-tests] === RUN   TestScriptTokenScriptFails
[niks3-go-unit-tests] === PAUSE TestScriptTokenScriptFails
[niks3-go-unit-tests] === RUN   TestScriptTokenEmptyCommand
[niks3-go-unit-tests] === PAUSE TestScriptTokenEmptyCommand
[niks3-go-unit-tests] === CONT  TestDoServerRequestAttachesToken
[niks3-go-unit-tests] === CONT  TestResolveStorePath
[niks3-go-unit-tests] === CONT  TestFileTokenMissing
[niks3-go-unit-tests] === CONT  TestSetClientTLSDoesNotMutateDefaultTransport
[niks3-go-unit-tests] === CONT  TestFileTokenReadsAndCaches
[niks3-go-unit-tests] --- PASS: TestResolveStorePath (0.00s)
[niks3-go-unit-tests] === CONT  TestRateLimiterFeedback_400DoesNotCountAsSuccess
[niks3-go-unit-tests] --- PASS: TestFileTokenMissing (0.00s)
[niks3-go-unit-tests] === CONT  TestRateLimiterFeedback
[niks3-go-unit-tests] === RUN   TestRateLimiterFeedback/429_enables_limiter
[niks3-go-unit-tests] === PAUSE TestRateLimiterFeedback/429_enables_limiter
[niks3-go-unit-tests] === RUN   TestRateLimiterFeedback/503_enables_limiter
[niks3-go-unit-tests] 2026/07/18 13:56:22 WARN Rate limiter enabled after throttle name=server-test rate=5
[niks3-go-unit-tests] === CONT  TestStaticToken
[niks3-go-unit-tests] === PAUSE TestRateLimiterFeedback/503_enables_limiter
[niks3-go-unit-tests] === RUN   TestRateLimiterFeedback/200_does_not_enable_limiter
[niks3-go-unit-tests] === PAUSE TestRateLimiterFeedback/200_does_not_enable_limiter
[niks3-go-unit-tests] === CONT  TestScriptTokenEmptyToken
[niks3-go-unit-tests] --- PASS: TestStaticToken (0.00s)
[niks3-go-unit-tests] === CONT  TestScriptTokenEmptyCommand
[niks3-go-unit-tests] --- PASS: TestScriptTokenEmptyCommand (0.00s)
[niks3-go-unit-tests] === CONT  TestParsePathInfoJSONMultiplePaths
[niks3-go-unit-tests] === CONT  TestScriptTokenScriptFails
[niks3-go-unit-tests] === CONT  TestScriptTokenBadJSON
[niks3-go-unit-tests] === CONT  TestScriptTokenNoExpiryRerunsEveryCall
[niks3-go-unit-tests] === CONT  TestScriptTokenCachesUntilRefresh
[niks3-go-unit-tests] === CONT  TestConvertHashToNix32
[niks3-go-unit-tests] === CONT  TestPathInfoHashCompatibility
[niks3-go-unit-tests] === RUN   TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)
[niks3-go-unit-tests] === PAUSE TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)
[niks3-go-unit-tests] === RUN   TestConvertHashToNix32/SRI_format_to_Nix32
[niks3-go-unit-tests] === PAUSE TestConvertHashToNix32/SRI_format_to_Nix32
[niks3-go-unit-tests] === CONT  TestSetClientTLSErrors
[niks3-go-unit-tests] === RUN   TestRateLimiterFeedback/400_does_not_enable_limiter
[niks3-go-unit-tests] === PAUSE TestRateLimiterFeedback/400_does_not_enable_limiter
[niks3-go-unit-tests] === CONT  TestGetStorePathHash
[niks3-go-unit-tests] === RUN   TestGetStorePathHash/valid_store_path
[niks3-go-unit-tests] === PAUSE TestGetStorePathHash/valid_store_path
[niks3-go-unit-tests] === RUN   TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths
[niks3-go-unit-tests] === PAUSE TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths
[niks3-go-unit-tests] === RUN   TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths
[niks3-go-unit-tests] === PAUSE TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths
[niks3-go-unit-tests] --- PASS: TestDoServerRequestAttachesToken (0.00s)
[niks3-go-unit-tests] === CONT  TestFileTokenEmpty
[niks3-go-unit-tests] === RUN   TestConvertHashToNix32/already_Nix32_format
[niks3-go-unit-tests] === CONT  TestParsePathInfoJSON
[niks3-go-unit-tests] === RUN   TestParsePathInfoJSON/Nix_format
[niks3-go-unit-tests] === PAUSE TestParsePathInfoJSON/Nix_format
[niks3-go-unit-tests] === RUN   TestParsePathInfoJSON/Lix_format
[niks3-go-unit-tests] === CONT  TestPathInfoCACompatibility
[niks3-go-unit-tests] --- PASS: TestFileTokenEmpty (0.00s)
[niks3-go-unit-tests] === RUN   TestPathInfoCACompatibility/null_ca_field
[niks3-go-unit-tests] === PAUSE TestPathInfoCACompatibility/null_ca_field
[niks3-go-unit-tests] === CONT  TestShellSplit
[niks3-go-unit-tests] --- PASS: TestShellSplit (0.00s)
[niks3-go-unit-tests] === RUN   TestPathInfoCACompatibility/old_string_format_-_text
[niks3-go-unit-tests] === CONT  TestDumpPathSingleFile
[niks3-go-unit-tests] === PAUSE TestPathInfoCACompatibility/old_string_format_-_text
[niks3-go-unit-tests] === RUN   TestSetClientTLSErrors/missing_cert_file
[niks3-go-unit-tests] === RUN   TestPathInfoCACompatibility/old_string_format_-_fixed_recursive
[niks3-go-unit-tests] === PAUSE TestSetClientTLSErrors/missing_cert_file
[niks3-go-unit-tests] === RUN   TestSetClientTLSErrors/missing_key_file
[niks3-go-unit-tests] === PAUSE TestSetClientTLSErrors/missing_key_file
[niks3-go-unit-tests] === RUN   TestSetClientTLSErrors/missing_ca_file
[niks3-go-unit-tests] === PAUSE TestSetClientTLSErrors/missing_ca_file
[niks3-go-unit-tests] === RUN   TestSetClientTLSErrors/invalid_ca_file
[niks3-go-unit-tests] === PAUSE TestSetClientTLSErrors/invalid_ca_file
[niks3-go-unit-tests] === PAUSE TestPathInfoCACompatibility/old_string_format_-_fixed_recursive
[niks3-go-unit-tests] === RUN   TestPathInfoCACompatibility/new_structured_format_-_text
[niks3-go-unit-tests] === PAUSE TestPathInfoCACompatibility/new_structured_format_-_text
[niks3-go-unit-tests] === RUN   TestPathInfoCACompatibility/new_structured_format_-_nar_method
[niks3-go-unit-tests] === CONT  TestEncodeNixBase32WithRealHash
[niks3-go-unit-tests] --- PASS: TestEncodeNixBase32WithRealHash (0.00s)
[niks3-go-unit-tests] === CONT  TestEncodeNixBase32
[niks3-go-unit-tests] === RUN   TestEncodeNixBase32/test_string_hash
[niks3-go-unit-tests] === PAUSE TestEncodeNixBase32/test_string_hash
[niks3-go-unit-tests] === RUN   TestEncodeNixBase32/empty_input
[niks3-go-unit-tests] === PAUSE TestEncodeNixBase32/empty_input
[niks3-go-unit-tests] === PAUSE TestConvertHashToNix32/already_Nix32_format
[niks3-go-unit-tests] === PAUSE TestParsePathInfoJSON/Lix_format
[niks3-go-unit-tests] === RUN   TestGetStorePathHash/basename_without_hyphen_should_error
[niks3-go-unit-tests] --- PASS: TestSetClientTLSDoesNotMutateDefaultTransport (0.00s)
[niks3-go-unit-tests] === RUN   TestParsePathInfoJSON/empty_input
[niks3-go-unit-tests] === PAUSE TestGetStorePathHash/basename_without_hyphen_should_error
[niks3-go-unit-tests] === PAUSE TestParsePathInfoJSON/empty_input
[niks3-go-unit-tests] === RUN   TestGetStorePathHash/hash_with_invalid_characters_should_error
[niks3-go-unit-tests] === PAUSE TestPathInfoCACompatibility/new_structured_format_-_nar_method
[niks3-go-unit-tests] === CONT  TestDumpPathWriterError
[niks3-go-unit-tests] === RUN   TestPathInfoHashCompatibility/old_string_format_with_colon
[niks3-go-unit-tests] === RUN   TestConvertHashToNix32/invalid_format
[niks3-go-unit-tests] === CONT  TestUploadMultipart_SupersededByPeer
[niks3-go-unit-tests] === PAUSE TestConvertHashToNix32/invalid_format
[niks3-go-unit-tests] === RUN   TestUploadMultipart_SupersededByPeer/exists
[niks3-go-unit-tests] === RUN   TestParsePathInfoJSON/whitespace_only
[niks3-go-unit-tests] === PAUSE TestParsePathInfoJSON/whitespace_only
[niks3-go-unit-tests] === CONT  TestDumpPathMatchesNix
[niks3-go-unit-tests] === PAUSE TestUploadMultipart_SupersededByPeer/exists
[niks3-go-unit-tests] === RUN   TestParsePathInfoJSON/invalid_JSON
[niks3-go-unit-tests] === PAUSE TestParsePathInfoJSON/invalid_JSON
[niks3-go-unit-tests] === CONT  TestPartSizeForNAR
[niks3-go-unit-tests] === CONT  TestCaseHackSuffix
[niks3-go-unit-tests] === RUN   TestPartSizeForNAR/zero_stays_at_minimum
[niks3-go-unit-tests] === PAUSE TestPartSizeForNAR/zero_stays_at_minimum
[niks3-go-unit-tests] === RUN   TestPartSizeForNAR/small_stays_at_minimum
[niks3-go-unit-tests] === PAUSE TestPartSizeForNAR/small_stays_at_minimum
[niks3-go-unit-tests] === RUN   TestPartSizeForNAR/80_GiB_fits_at_minimum
[niks3-go-unit-tests] === PAUSE TestPartSizeForNAR/80_GiB_fits_at_minimum
[niks3-go-unit-tests] === RUN   TestPartSizeForNAR/115_GiB_needs_larger_parts
[niks3-go-unit-tests] === PAUSE TestPartSizeForNAR/115_GiB_needs_larger_parts
[niks3-go-unit-tests] === PAUSE TestGetStorePathHash/hash_with_invalid_characters_should_error
[niks3-go-unit-tests] === RUN   TestGetStorePathHash/hash_with_wrong_length_should_error
[niks3-go-unit-tests] === PAUSE TestGetStorePathHash/hash_with_wrong_length_should_error
[niks3-go-unit-tests] === CONT  TestDoWithRetry_BodyReplayedViaGetBody
[niks3-go-unit-tests] === CONT  TestShellSplitErrors
[niks3-go-unit-tests] === CONT  TestRateLimiterFeedback/429_enables_limiter
[niks3-go-unit-tests] --- PASS: TestScriptTokenEmptyToken (0.00s)
[niks3-go-unit-tests] === RUN   TestUploadMultipart_SupersededByPeer/missing
[niks3-go-unit-tests] === PAUSE TestUploadMultipart_SupersededByPeer/missing
[niks3-go-unit-tests] --- PASS: TestScriptTokenScriptFails (0.00s)
[niks3-go-unit-tests] --- PASS: TestScriptTokenBadJSON (0.00s)
[niks3-go-unit-tests] --- PASS: TestShellSplitErrors (0.00s)
[niks3-go-unit-tests] === CONT  TestSetClientTLS
[niks3-go-unit-tests] === CONT  TestRateLimiterFeedback/200_does_not_enable_limiter
[niks3-go-unit-tests] === RUN   TestPartSizeForNAR/1_TiB
[niks3-go-unit-tests] 2026/07/18 13:56:22 WARN Rate limiter enabled after throttle name=server-test rate=5
[niks3-go-unit-tests] 2026/07/18 13:56:22 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=429 url=http://127.0.0.1:44317
[niks3-go-unit-tests] === PAUSE TestPathInfoHashCompatibility/old_string_format_with_colon
[niks3-go-unit-tests] === RUN   TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI
[niks3-go-unit-tests] === PAUSE TestPartSizeForNAR/1_TiB
[niks3-go-unit-tests] === PAUSE TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI
[niks3-go-unit-tests] === RUN   TestPathInfoHashCompatibility/new_structured_format_with_sha512
[niks3-go-unit-tests] === RUN   TestPartSizeForNAR/5_TiB_S3_max_object
[niks3-go-unit-tests] === PAUSE TestPathInfoHashCompatibility/new_structured_format_with_sha512
[niks3-go-unit-tests] === PAUSE TestPartSizeForNAR/5_TiB_S3_max_object
[niks3-go-unit-tests] === RUN   TestPartSizeForNAR/capped_at_5_GiB
[niks3-go-unit-tests] === PAUSE TestPartSizeForNAR/capped_at_5_GiB
[niks3-go-unit-tests] === CONT  TestRateLimiterFeedback/400_does_not_enable_limiter
[niks3-go-unit-tests] === CONT  TestRateLimiterFeedback/503_enables_limiter
[niks3-go-unit-tests] 2026/07/18 13:56:22 WARN Rate limiter backed off name=server-test rate=5
[niks3-go-unit-tests] === RUN   TestSetClientTLS/rejects_connection_without_client_cert
[niks3-go-unit-tests] === PAUSE TestSetClientTLS/rejects_connection_without_client_cert
[niks3-go-unit-tests] === RUN   TestSetClientTLS/succeeds_with_client_cert_and_CA
[niks3-go-unit-tests] === PAUSE TestSetClientTLS/succeeds_with_client_cert_and_CA
[niks3-go-unit-tests] === RUN   TestSetClientTLS/preserves_debug_logging_transport
[niks3-go-unit-tests] === PAUSE TestSetClientTLS/preserves_debug_logging_transport
[niks3-go-unit-tests] === CONT  TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths
[niks3-go-unit-tests] === CONT  TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths
[niks3-go-unit-tests] --- PASS: TestParsePathInfoJSONMultiplePaths (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParsePathInfoJSONMultiplePaths/Nix_multiple_paths (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParsePathInfoJSONMultiplePaths/Lix_multiple_paths (0.00s)
[niks3-go-unit-tests] === CONT  TestSetClientTLSErrors/missing_cert_file
[niks3-go-unit-tests] === CONT  TestSetClientTLSErrors/missing_ca_file
[niks3-go-unit-tests] 2026/07/18 13:56:22 WARN Rate limiter enabled after throttle name=server-test rate=5
[niks3-go-unit-tests] 2026/07/18 13:56:22 WARN Request returned retryable status, retrying attempt=1 max_attempts=2 backoff=0s status=503 url=http://127.0.0.1:41909
[niks3-go-unit-tests] === CONT  TestSetClientTLSErrors/invalid_ca_file
[niks3-go-unit-tests] === CONT  TestSetClientTLSErrors/missing_key_file
[niks3-go-unit-tests] === CONT  TestEncodeNixBase32/test_string_hash
[niks3-go-unit-tests] === CONT  TestEncodeNixBase32/empty_input
[niks3-go-unit-tests] --- PASS: TestEncodeNixBase32 (0.00s)
[niks3-go-unit-tests]     --- PASS: TestEncodeNixBase32/test_string_hash (0.00s)
[niks3-go-unit-tests]     --- PASS: TestEncodeNixBase32/empty_input (0.00s)
[niks3-go-unit-tests] === CONT  TestPathInfoCACompatibility/null_ca_field
[niks3-go-unit-tests] === CONT  TestPathInfoCACompatibility/new_structured_format_-_text
[niks3-go-unit-tests] 2026/07/18 13:56:22 WARN Rate limiter backed off name=server-test rate=5
[niks3-go-unit-tests] 2026/07/18 13:56:22 WARN Rate limiter enabled after throttle name=server-test rate=5
[niks3-go-unit-tests] 2026/07/18 13:56:22 WARN Request returned retryable status, retrying attempt=1 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:33679
[niks3-go-unit-tests] === CONT  TestPathInfoCACompatibility/old_string_format_-_fixed_recursive
[niks3-go-unit-tests] === CONT  TestPathInfoCACompatibility/old_string_format_-_text
[niks3-go-unit-tests] === CONT  TestPathInfoCACompatibility/new_structured_format_-_nar_method
[niks3-go-unit-tests] === CONT  TestConvertHashToNix32/already_Nix32_format
[niks3-go-unit-tests] === CONT  TestConvertHashToNix32/SRI_format_to_Nix32
[niks3-go-unit-tests] === CONT  TestParsePathInfoJSON/Nix_format
[niks3-go-unit-tests] === CONT  TestConvertHashToNix32/invalid_format
[niks3-go-unit-tests] === CONT  TestParsePathInfoJSON/whitespace_only
[niks3-go-unit-tests] === CONT  TestParsePathInfoJSON/empty_input
[niks3-go-unit-tests] === CONT  TestParsePathInfoJSON/Lix_format
[niks3-go-unit-tests] === CONT  TestGetStorePathHash/hash_with_wrong_length_should_error
[niks3-go-unit-tests] === CONT  TestParsePathInfoJSON/invalid_JSON
[niks3-go-unit-tests] === CONT  TestGetStorePathHash/hash_with_invalid_characters_should_error
[niks3-go-unit-tests] === CONT  TestGetStorePathHash/valid_store_path
[niks3-go-unit-tests] === CONT  TestUploadMultipart_SupersededByPeer/exists
[niks3-go-unit-tests] === CONT  TestGetStorePathHash/basename_without_hyphen_should_error
[niks3-go-unit-tests] === CONT  TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI)
[niks3-go-unit-tests] === CONT  TestUploadMultipart_SupersededByPeer/missing
[niks3-go-unit-tests] --- PASS: TestConvertHashToNix32 (0.00s)
[niks3-go-unit-tests]     --- PASS: TestConvertHashToNix32/already_Nix32_format (0.00s)
[niks3-go-unit-tests]     --- PASS: TestConvertHashToNix32/SRI_format_to_Nix32 (0.00s)
[niks3-go-unit-tests]     --- PASS: TestConvertHashToNix32/invalid_format (0.00s)
[niks3-go-unit-tests] --- PASS: TestRateLimiterFeedback (0.00s)
[niks3-go-unit-tests]     --- PASS: TestRateLimiterFeedback/429_enables_limiter (0.01s)
[niks3-go-unit-tests]     --- PASS: TestRateLimiterFeedback/400_does_not_enable_limiter (0.01s)
[niks3-go-unit-tests]     --- PASS: TestRateLimiterFeedback/503_enables_limiter (0.01s)
[niks3-go-unit-tests]     --- PASS: TestRateLimiterFeedback/200_does_not_enable_limiter (0.01s)
[niks3-go-unit-tests] === CONT  TestPathInfoHashCompatibility/new_structured_format_with_sha512
[niks3-go-unit-tests] === CONT  TestPathInfoHashCompatibility/old_string_format_with_colon
[niks3-go-unit-tests] 2026/07/18 13:56:22 WARN Rate limiter backed off name=server-test rate=5
[niks3-go-unit-tests] === CONT  TestPartSizeForNAR/zero_stays_at_minimum
[niks3-go-unit-tests] === CONT  TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI
[niks3-go-unit-tests] 2026/07/18 13:56:22 WARN Request returned retryable status, retrying attempt=2 max_attempts=6 backoff=0s status=503 url=http://127.0.0.1:33679
[niks3-go-unit-tests] === CONT  TestPartSizeForNAR/1_TiB
[niks3-go-unit-tests] --- PASS: TestPathInfoCACompatibility (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPathInfoCACompatibility/null_ca_field (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_text (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPathInfoCACompatibility/old_string_format_-_fixed_recursive (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPathInfoCACompatibility/old_string_format_-_text (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPathInfoCACompatibility/new_structured_format_-_nar_method (0.00s)
[niks3-go-unit-tests] === CONT  TestPartSizeForNAR/80_GiB_fits_at_minimum
[niks3-go-unit-tests] === CONT  TestPartSizeForNAR/small_stays_at_minimum
[niks3-go-unit-tests] --- PASS: TestGetStorePathHash (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGetStorePathHash/hash_with_wrong_length_should_error (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGetStorePathHash/valid_store_path (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGetStorePathHash/hash_with_invalid_characters_should_error (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGetStorePathHash/basename_without_hyphen_should_error (0.00s)
[niks3-go-unit-tests] === CONT  TestPartSizeForNAR/5_TiB_S3_max_object
[niks3-go-unit-tests] === CONT  TestSetClientTLS/rejects_connection_without_client_cert
[niks3-go-unit-tests] --- PASS: TestParsePathInfoJSON (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParsePathInfoJSON/Nix_format (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParsePathInfoJSON/empty_input (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParsePathInfoJSON/whitespace_only (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParsePathInfoJSON/invalid_JSON (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParsePathInfoJSON/Lix_format (0.00s)
[niks3-go-unit-tests] --- PASS: TestPathInfoHashCompatibility (0.01s)
[niks3-go-unit-tests]     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_dash_(SRI) (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPathInfoHashCompatibility/old_string_format_with_colon (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPathInfoHashCompatibility/new_structured_format_with_sha512 (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPathInfoHashCompatibility/new_structured_format_-_converts_to_SRI (0.00s)
[niks3-go-unit-tests] --- PASS: TestSetClientTLSErrors (0.00s)
[niks3-go-unit-tests]     --- PASS: TestSetClientTLSErrors/missing_cert_file (0.00s)
[niks3-go-unit-tests]     --- PASS: TestSetClientTLSErrors/missing_ca_file (0.00s)
[niks3-go-unit-tests]     --- PASS: TestSetClientTLSErrors/missing_key_file (0.00s)
[niks3-go-unit-tests]     --- PASS: TestSetClientTLSErrors/invalid_ca_file (0.01s)
[niks3-go-unit-tests] 2026/07/18 13:56:22 http: TLS handshake error from 127.0.0.1:44286: remote error: tls: bad certificate
[niks3-go-unit-tests] === CONT  TestSetClientTLS/preserves_debug_logging_transport
[niks3-go-unit-tests] === CONT  TestPartSizeForNAR/115_GiB_needs_larger_parts
[niks3-go-unit-tests] === CONT  TestPartSizeForNAR/capped_at_5_GiB
[niks3-go-unit-tests] === CONT  TestSetClientTLS/succeeds_with_client_cert_and_CA
[niks3-go-unit-tests] --- PASS: TestPartSizeForNAR (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPartSizeForNAR/zero_stays_at_minimum (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPartSizeForNAR/1_TiB (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPartSizeForNAR/80_GiB_fits_at_minimum (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPartSizeForNAR/small_stays_at_minimum (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPartSizeForNAR/5_TiB_S3_max_object (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPartSizeForNAR/115_GiB_needs_larger_parts (0.00s)
[niks3-go-unit-tests]     --- PASS: TestPartSizeForNAR/capped_at_5_GiB (0.00s)
[niks3-go-unit-tests] --- PASS: TestDoWithRetry_BodyReplayedViaGetBody (0.02s)
[niks3-go-unit-tests] --- PASS: TestUploadMultipart_SupersededByPeer (0.00s)
[niks3-go-unit-tests]     --- PASS: TestUploadMultipart_SupersededByPeer/exists (0.01s)
[niks3-go-unit-tests]     --- PASS: TestUploadMultipart_SupersededByPeer/missing (0.01s)
[niks3-go-unit-tests] --- PASS: TestSetClientTLS (0.01s)
[niks3-go-unit-tests]     --- PASS: TestSetClientTLS/rejects_connection_without_client_cert (0.00s)
[niks3-go-unit-tests]     --- PASS: TestSetClientTLS/preserves_debug_logging_transport (0.00s)
[niks3-go-unit-tests]     --- PASS: TestSetClientTLS/succeeds_with_client_cert_and_CA (0.00s)
[niks3-go-unit-tests] --- PASS: TestCaseHackSuffix (0.02s)
[niks3-go-unit-tests] --- PASS: TestDumpPathSingleFile (0.03s)
[niks3-go-unit-tests] --- PASS: TestDumpPathWriterError (0.03s)
[niks3-go-unit-tests] --- PASS: TestDumpPathMatchesNix (0.05s)
[niks3-go-unit-tests] --- PASS: TestFileTokenReadsAndCaches (0.06s)
[niks3-go-unit-tests] --- PASS: TestScriptTokenNoExpiryRerunsEveryCall (0.06s)
[niks3-go-unit-tests] --- PASS: TestScriptTokenCachesUntilRefresh (0.06s)
[niks3-go-unit-tests] --- PASS: TestRateLimiterFeedback_400DoesNotCountAsSuccess (1.00s)
[niks3-go-unit-tests] PASS
[niks3-go-unit-tests] Running server tests...
[niks3-go-unit-tests] The files belonging to this database system will be owned by user "nixbld".
[niks3-go-unit-tests] This user must also own the server process.
[niks3-go-unit-tests] 
[niks3-go-unit-tests] The database cluster will be initialized with locale "C".
[niks3-go-unit-tests] The default database encoding has accordingly been set to "SQL_ASCII".
[niks3-go-unit-tests] The default text search configuration will be set to "english".
[niks3-go-unit-tests] 
[niks3-go-unit-tests] Data page checksums are enabled.
[niks3-go-unit-tests] 
[niks3-go-unit-tests] creating directory /build/postgres213714210/data ... ok
[niks3-go-unit-tests] creating subdirectories ... ok
[niks3-go-unit-tests] selecting dynamic shared memory implementation ... posix
[niks3-go-unit-tests] selecting default "max_connections" ... 100
[niks3-go-unit-tests] selecting default "shared_buffers" ... 128MB
[niks3-go-unit-tests] selecting default time zone ... UTC
[niks3-go-unit-tests] creating configuration files ... ok
[niks3-go-unit-tests] running bootstrap script ... ok
[niks3-go-unit-tests] performing post-bootstrap initialization ... ok
[niks3-go-unit-tests] syncing data to disk ... ok
[niks3-go-unit-tests] 
[niks3-go-unit-tests] initdb: warning: enabling "trust" authentication for local connections
[niks3-go-unit-tests] initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.
[niks3-go-unit-tests] 
[niks3-go-unit-tests] Success. You can now start the database server using:
[niks3-go-unit-tests] 
[niks3-go-unit-tests]     pg_ctl -D /build/postgres213714210/data -l logfile start
[niks3-go-unit-tests] 
[niks3-go-unit-tests] /build/postgres213714210:5432 - no response
[niks3-go-unit-tests] 2026-07-18 13:56:24.711 UTC [99] LOG:  starting PostgreSQL 18.4 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit
[niks3-go-unit-tests] 2026-07-18 13:56:24.719 UTC [99] LOG:  listening on Unix socket "/build/postgres213714210/.s.PGSQL.5432"
[niks3-go-unit-tests] 2026-07-18 13:56:24.749 UTC [106] LOG:  database system was shut down at 2026-07-18 13:56:24 UTC
[niks3-go-unit-tests] 2026-07-18 13:56:24.759 UTC [99] LOG:  database system is ready to accept connections
[niks3-go-unit-tests] /build/postgres213714210:5432 - accepting connections
[niks3-go-unit-tests] {"timestamp":"2026-07-18T13:56:24.811495258Z","level":"ERROR","fields":{"message":"list_path_raw: revjob err VolumeNotFound"},"target":"rustfs_ecstore::cache_value::metacache_set","filename":"crates/ecstore/src/cache_value/metacache_set.rs","line_number":486,"threadName":"rustfs-worker","threadId":"ThreadId(16)"}
[niks3-go-unit-tests] 
[niks3-go-unit-tests] thread 'rustfs-worker' (131) panicked at /build/rustfs-1.0.0-beta.7-vendor/source-registry-0/reqwest-0.13.4/src/async_impl/client.rs:2507:38:
[niks3-go-unit-tests] Client::new(): reqwest::Error { kind: Builder, source: General("No CA certificates were loaded from the system") }
[niks3-go-unit-tests] note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace
[niks3-go-unit-tests] === RUN   TestService_AuthMiddleware
[niks3-go-unit-tests] === PAUSE TestService_AuthMiddleware
[niks3-go-unit-tests] === RUN   TestService_AuthMiddleware_MTLSProxyHeader
[niks3-go-unit-tests] === PAUSE TestService_AuthMiddleware_MTLSProxyHeader
[niks3-go-unit-tests] === RUN   TestService_AuthMiddleware_MTLSBoundSubjects
[niks3-go-unit-tests] === PAUSE TestService_AuthMiddleware_MTLSBoundSubjects
[niks3-go-unit-tests] === RUN   TestService_ReadAuthMiddleware
[niks3-go-unit-tests] === PAUSE TestService_ReadAuthMiddleware
[niks3-go-unit-tests] === RUN   TestService_AuthMiddleware_OIDC
[niks3-go-unit-tests] === PAUSE TestService_AuthMiddleware_OIDC
[niks3-go-unit-tests] === RUN   TestCacheConfigHandler
[niks3-go-unit-tests] === PAUSE TestCacheConfigHandler
[niks3-go-unit-tests] === RUN   TestCacheStatsHandler
[niks3-go-unit-tests] === PAUSE TestCacheStatsHandler
[niks3-go-unit-tests] === RUN   TestClientCADerivations
[niks3-go-unit-tests] === PAUSE TestClientCADerivations
[niks3-go-unit-tests] === RUN   TestClientErrorHandling
[niks3-go-unit-tests] === PAUSE TestClientErrorHandling
[niks3-go-unit-tests] === RUN   TestClientIntegration
[niks3-go-unit-tests] === PAUSE TestClientIntegration
[niks3-go-unit-tests] === RUN   TestClientMultipleUploads
[niks3-go-unit-tests] === PAUSE TestClientMultipleUploads
[niks3-go-unit-tests] === RUN   TestClientWithDependencies
[niks3-go-unit-tests] === PAUSE TestClientWithDependencies
[niks3-go-unit-tests] === RUN   TestPinProtectsFromGC
[niks3-go-unit-tests] === PAUSE TestPinProtectsFromGC
[niks3-go-unit-tests] === RUN   TestGCAdvisoryLockBlocksConcurrentRun
[niks3-go-unit-tests] 2026-07-18 13:56:25.004 UTC [160] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:25.004 UTC [160] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026/07/18 13:56:25 OK   20241026095416_initial_model.sql (8.43ms)
[niks3-go-unit-tests] 2026/07/18 13:56:25 OK   20251210153512_drop_unused_gin_index.sql (5.11ms)
[niks3-go-unit-tests] 2026/07/18 13:56:25 OK   20251218171726_add_pins.sql (6.19ms)
[niks3-go-unit-tests] 2026/07/18 13:56:25 OK   20260628120000_add_object_size_and_stats.sql (5.01ms)
[niks3-go-unit-tests] 2026/07/18 13:56:25 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:25 OK   1_commit_pending_closure.sql (4.74ms)
[niks3-go-unit-tests] 2026/07/18 13:56:25 OK   2_object_stats_trigger.sql (4.94ms)
[niks3-go-unit-tests] 2026/07/18 13:56:25 goose: up to current file version: 2
[niks3-go-unit-tests] --- PASS: TestGCAdvisoryLockBlocksConcurrentRun (0.16s)
[niks3-go-unit-tests] === RUN   TestGCBugBareHashReferences
[niks3-go-unit-tests] === PAUSE TestGCBugBareHashReferences
[niks3-go-unit-tests] === RUN   TestGCMetrics
[niks3-go-unit-tests] === PAUSE TestGCMetrics
[niks3-go-unit-tests] === RUN   TestGCTaskStore_StartNew
[niks3-go-unit-tests] === PAUSE TestGCTaskStore_StartNew
[niks3-go-unit-tests] === RUN   TestGCTaskStore_DeduplicateSameParams
[niks3-go-unit-tests] === PAUSE TestGCTaskStore_DeduplicateSameParams
[niks3-go-unit-tests] === RUN   TestGCTaskStore_ConflictDifferentParams
[niks3-go-unit-tests] === PAUSE TestGCTaskStore_ConflictDifferentParams
[niks3-go-unit-tests] === RUN   TestGCTaskStore_GetEmpty
[niks3-go-unit-tests] === PAUSE TestGCTaskStore_GetEmpty
[niks3-go-unit-tests] === RUN   TestGCTaskStore_GetReturnsLatest
[niks3-go-unit-tests] === PAUSE TestGCTaskStore_GetReturnsLatest
[niks3-go-unit-tests] === RUN   TestGCTaskStore_CompletedAllowsNewTask
[niks3-go-unit-tests] === PAUSE TestGCTaskStore_CompletedAllowsNewTask
[niks3-go-unit-tests] === RUN   TestGCTaskStore_PhaseUpdates
[niks3-go-unit-tests] === PAUSE TestGCTaskStore_PhaseUpdates
[niks3-go-unit-tests] === RUN   TestGCTaskStore_Fail
[niks3-go-unit-tests] === PAUSE TestGCTaskStore_Fail
[niks3-go-unit-tests] === RUN   TestGracefulShutdownDrainsInflight
[niks3-go-unit-tests] === PAUSE TestGracefulShutdownDrainsInflight
[niks3-go-unit-tests] === RUN   TestService_healthCheckHandler
[niks3-go-unit-tests] === PAUSE TestService_healthCheckHandler
[niks3-go-unit-tests] === RUN   TestGenerateLandingPage
[niks3-go-unit-tests] === PAUSE TestGenerateLandingPage
[niks3-go-unit-tests] === RUN   TestNARDeduplicationMetadataUploadBug
[niks3-go-unit-tests] === PAUSE TestNARDeduplicationMetadataUploadBug
[niks3-go-unit-tests] === RUN   TestMetricsInventory
[niks3-go-unit-tests] === PAUSE TestMetricsInventory
[niks3-go-unit-tests] === RUN   TestService_NativeMTLS
[niks3-go-unit-tests] === PAUSE TestService_NativeMTLS
[niks3-go-unit-tests] === RUN   TestServerTLSConfig
[niks3-go-unit-tests] === PAUSE TestServerTLSConfig
[niks3-go-unit-tests] === RUN   TestMultipartCleanup
[niks3-go-unit-tests] === PAUSE TestMultipartCleanup
[niks3-go-unit-tests] === RUN   TestObjectStatsTrigger
[niks3-go-unit-tests] === PAUSE TestObjectStatsTrigger
[niks3-go-unit-tests] === RUN   TestOrphanedObjectsGC
[niks3-go-unit-tests] === PAUSE TestOrphanedObjectsGC
[niks3-go-unit-tests] === RUN   TestOrphanedObjectsGCStressTest
[niks3-go-unit-tests] === PAUSE TestOrphanedObjectsGCStressTest
[niks3-go-unit-tests] === RUN   TestResurrectedObjectNotDeleted
[niks3-go-unit-tests] === PAUSE TestResurrectedObjectNotDeleted
[niks3-go-unit-tests] === RUN   TestParseSingleRange
[niks3-go-unit-tests] === PAUSE TestParseSingleRange
[niks3-go-unit-tests] === RUN   TestIsValidCachePath
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath
[niks3-go-unit-tests] === RUN   TestReadProxyNarinfo
[niks3-go-unit-tests] === PAUSE TestReadProxyNarinfo
[niks3-go-unit-tests] === RUN   TestReadProxyNarinfoAlreadyDecompressed
[niks3-go-unit-tests] === PAUSE TestReadProxyNarinfoAlreadyDecompressed
[niks3-go-unit-tests] === RUN   TestReadProxyNarStreaming
[niks3-go-unit-tests] === PAUSE TestReadProxyNarStreaming
[niks3-go-unit-tests] === RUN   TestReadProxy404
[niks3-go-unit-tests] === PAUSE TestReadProxy404
[niks3-go-unit-tests] === RUN   TestReadProxyInvalidPath
[niks3-go-unit-tests] === PAUSE TestReadProxyInvalidPath
[niks3-go-unit-tests] === RUN   TestReadProxyHead
[niks3-go-unit-tests] === PAUSE TestReadProxyHead
[niks3-go-unit-tests] === RUN   TestReadProxyConditionalGet
[niks3-go-unit-tests] === PAUSE TestReadProxyConditionalGet
[niks3-go-unit-tests] === RUN   TestReadProxyRootRedirectsToIndexHTML
[niks3-go-unit-tests] === PAUSE TestReadProxyRootRedirectsToIndexHTML
[niks3-go-unit-tests] === RUN   TestReadProxyDisabled
[niks3-go-unit-tests] === PAUSE TestReadProxyDisabled
[niks3-go-unit-tests] === RUN   TestReadProxyRangeRequest
[niks3-go-unit-tests] === PAUSE TestReadProxyRangeRequest
[niks3-go-unit-tests] === RUN   TestRedundantMultipartUpload
[niks3-go-unit-tests] === PAUSE TestRedundantMultipartUpload
[niks3-go-unit-tests] === RUN   TestCompleteMultipartUpload_ErrorButObjectExists
[niks3-go-unit-tests] === PAUSE TestCompleteMultipartUpload_ErrorButObjectExists
[niks3-go-unit-tests] === RUN   TestService_Rustfstest
[niks3-go-unit-tests] === PAUSE TestService_Rustfstest
[niks3-go-unit-tests] === RUN   TestSystemdListenerNotActivated
[niks3-go-unit-tests] --- PASS: TestSystemdListenerNotActivated (0.00s)
[niks3-go-unit-tests] === RUN   TestWatchdogBeatsWhenHealthy
[niks3-go-unit-tests] --- PASS: TestWatchdogBeatsWhenHealthy (0.02s)
[niks3-go-unit-tests] === RUN   TestWatchdogSkipsWhenUnhealthy
[niks3-go-unit-tests] 2026/07/18 13:56:25 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
[niks3-go-unit-tests] 2026/07/18 13:56:25 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
[niks3-go-unit-tests] 2026/07/18 13:56:25 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
[niks3-go-unit-tests] 2026/07/18 13:56:25 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
[niks3-go-unit-tests] 2026/07/18 13:56:25 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
[niks3-go-unit-tests] 2026/07/18 13:56:25 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
[niks3-go-unit-tests] 2026/07/18 13:56:25 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
[niks3-go-unit-tests] 2026/07/18 13:56:25 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
[niks3-go-unit-tests] 2026/07/18 13:56:25 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
[niks3-go-unit-tests] 2026/07/18 13:56:25 WARN watchdog liveness check failed, skipping heartbeat error="context deadline exceeded"
[niks3-go-unit-tests] --- PASS: TestWatchdogSkipsWhenUnhealthy (0.20s)
[niks3-go-unit-tests] === RUN   TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle
[niks3-go-unit-tests] === PAUSE TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle
[niks3-go-unit-tests] === RUN   TestProxyWriteTimeout
[niks3-go-unit-tests] === PAUSE TestProxyWriteTimeout
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey
[niks3-go-unit-tests] === RUN   TestUploadHandlersRejectInvalidKeys
[niks3-go-unit-tests] === PAUSE TestUploadHandlersRejectInvalidKeys
[niks3-go-unit-tests] === RUN   TestUploadHandlersRejectOversizedBody
[niks3-go-unit-tests] === PAUSE TestUploadHandlersRejectOversizedBody
[niks3-go-unit-tests] === RUN   TestService_cleanupPendingClosuresHandler
[niks3-go-unit-tests] === PAUSE TestService_cleanupPendingClosuresHandler
[niks3-go-unit-tests] === RUN   TestService_createPendingClosureHandler
[niks3-go-unit-tests] === PAUSE TestService_createPendingClosureHandler
[niks3-go-unit-tests] === RUN   TestService_verifyS3Integrity
[niks3-go-unit-tests] === PAUSE TestService_verifyS3Integrity
[niks3-go-unit-tests] === RUN   TestCompleteMultipartUnregistered
[niks3-go-unit-tests] === PAUSE TestCompleteMultipartUnregistered
[niks3-go-unit-tests] === RUN   TestCreatePendingClosure_SmallNARUsesSimplePUT
[niks3-go-unit-tests] === PAUSE TestCreatePendingClosure_SmallNARUsesSimplePUT
[niks3-go-unit-tests] === CONT  TestService_AuthMiddleware
[niks3-go-unit-tests] === CONT  TestMultipartCleanup
[niks3-go-unit-tests] === CONT  TestReadProxyDisabled
[niks3-go-unit-tests] === CONT  TestUploadHandlersRejectInvalidKeys
[niks3-go-unit-tests] === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info
[niks3-go-unit-tests] === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info
[niks3-go-unit-tests] === CONT  TestResurrectedObjectNotDeleted
[niks3-go-unit-tests] === CONT  TestReadProxyHead
[niks3-go-unit-tests] === RUN   TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal
[niks3-go-unit-tests] === PAUSE TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal
[niks3-go-unit-tests] === RUN   TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key
[niks3-go-unit-tests] === PAUSE TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key
[niks3-go-unit-tests] === CONT  TestReadProxyInvalidPath
[niks3-go-unit-tests] === CONT  TestReadProxy404
[niks3-go-unit-tests] === CONT  TestReadProxyNarStreaming
[niks3-go-unit-tests] === CONT  TestGCTaskStore_StartNew
[niks3-go-unit-tests] --- PASS: TestGCTaskStore_StartNew (0.00s)
[niks3-go-unit-tests] === CONT  TestNARDeduplicationMetadataUploadBug
[niks3-go-unit-tests] === CONT  TestServerTLSConfig
[niks3-go-unit-tests] === RUN   TestServerTLSConfig/no_client_CA
[niks3-go-unit-tests] === PAUSE TestServerTLSConfig/no_client_CA
[niks3-go-unit-tests] === RUN   TestServerTLSConfig/missing_CA_file
[niks3-go-unit-tests] === CONT  TestService_NativeMTLS
[niks3-go-unit-tests] === CONT  TestMetricsInventory
[niks3-go-unit-tests] === CONT  TestReadProxyNarinfoAlreadyDecompressed
[niks3-go-unit-tests] === RUN   TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key
[niks3-go-unit-tests] === PAUSE TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key
[niks3-go-unit-tests] === CONT  TestGenerateLandingPage
[niks3-go-unit-tests] === PAUSE TestServerTLSConfig/missing_CA_file
[niks3-go-unit-tests] === RUN   TestServerTLSConfig/not_a_PEM_file
[niks3-go-unit-tests] === PAUSE TestServerTLSConfig/not_a_PEM_file
[niks3-go-unit-tests] === CONT  TestService_healthCheckHandler
[niks3-go-unit-tests] --- PASS: TestGenerateLandingPage (0.00s)
[niks3-go-unit-tests] === CONT  TestGracefulShutdownDrainsInflight
[niks3-go-unit-tests] 2026/07/18 13:56:25 INFO Starting HTTP server address=127.0.0.1:46307
[niks3-go-unit-tests] 2026/07/18 13:56:25 INFO Shutdown signal received, draining in-flight requests timeout=10s
[niks3-go-unit-tests] --- PASS: TestGracefulShutdownDrainsInflight (0.07s)
[niks3-go-unit-tests] === CONT  TestGCTaskStore_Fail
[niks3-go-unit-tests] --- PASS: TestGCTaskStore_Fail (0.00s)
[niks3-go-unit-tests] === CONT  TestGCTaskStore_PhaseUpdates
[niks3-go-unit-tests] --- PASS: TestGCTaskStore_PhaseUpdates (0.00s)
[niks3-go-unit-tests] === CONT  TestGCTaskStore_CompletedAllowsNewTask
[niks3-go-unit-tests] --- PASS: TestGCTaskStore_CompletedAllowsNewTask (0.00s)
[niks3-go-unit-tests] === CONT  TestGCTaskStore_GetReturnsLatest
[niks3-go-unit-tests] --- PASS: TestGCTaskStore_GetReturnsLatest (0.00s)
[niks3-go-unit-tests] === CONT  TestGCTaskStore_GetEmpty
[niks3-go-unit-tests] --- PASS: TestGCTaskStore_GetEmpty (0.00s)
[niks3-go-unit-tests] === CONT  TestGCTaskStore_ConflictDifferentParams
[niks3-go-unit-tests] --- PASS: TestGCTaskStore_ConflictDifferentParams (0.00s)
[niks3-go-unit-tests] === CONT  TestGCTaskStore_DeduplicateSameParams
[niks3-go-unit-tests] --- PASS: TestGCTaskStore_DeduplicateSameParams (0.00s)
[niks3-go-unit-tests] === CONT  TestParseSingleRange
[niks3-go-unit-tests] === RUN   TestParseSingleRange/none
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/none
[niks3-go-unit-tests] === RUN   TestParseSingleRange/unknown_unit
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/unknown_unit
[niks3-go-unit-tests] === RUN   TestParseSingleRange/multi-range_ignored
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/multi-range_ignored
[niks3-go-unit-tests] === RUN   TestParseSingleRange/malformed_no_dash
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/malformed_no_dash
[niks3-go-unit-tests] === RUN   TestParseSingleRange/malformed_both_empty
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/malformed_both_empty
[niks3-go-unit-tests] === RUN   TestParseSingleRange/malformed_end_before_start
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/malformed_end_before_start
[niks3-go-unit-tests] === RUN   TestParseSingleRange/closed
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/closed
[niks3-go-unit-tests] === RUN   TestParseSingleRange/open-ended
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/open-ended
[niks3-go-unit-tests] === RUN   TestParseSingleRange/end_clamped_to_size
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/end_clamped_to_size
[niks3-go-unit-tests] === RUN   TestParseSingleRange/suffix
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/suffix
[niks3-go-unit-tests] === RUN   TestParseSingleRange/suffix_exceeds_size
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/suffix_exceeds_size
[niks3-go-unit-tests] === RUN   TestParseSingleRange/single_byte
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/single_byte
[niks3-go-unit-tests] === RUN   TestParseSingleRange/start_past_EOF
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/start_past_EOF
[niks3-go-unit-tests] === RUN   TestParseSingleRange/start_far_past_EOF
[niks3-go-unit-tests] === PAUSE TestParseSingleRange/start_far_past_EOF
[niks3-go-unit-tests] === CONT  TestReadProxyRootRedirectsToIndexHTML
[niks3-go-unit-tests] 2026-07-18 13:56:25.989 UTC [202] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:25.989 UTC [202] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.004 UTC [204] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.004 UTC [204] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.004 UTC [203] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.004 UTC [203] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.005 UTC [205] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.005 UTC [205] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.011 UTC [206] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.011 UTC [206] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.014 UTC [207] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.014 UTC [207] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.015 UTC [209] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.015 UTC [209] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.016 UTC [210] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.016 UTC [210] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.017 UTC [208] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.017 UTC [208] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.017 UTC [212] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.017 UTC [212] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.018 UTC [214] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.018 UTC [214] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.019 UTC [213] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.019 UTC [213] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.019 UTC [211] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.019 UTC [211] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (19.44ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (17.73ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (10.95ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (11.99ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (12.21ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (14.61ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (9.79ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (9.24ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (13.42ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (12.86ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (7.95ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (13.87ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (13.18ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (13.94ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (13.74ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (7.24ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (13.97ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (8.34ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (16.97ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (8.54ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (7.62ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (7.55ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (7.53ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (7.63ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (8.77ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (8.37ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (7.66ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (7.66ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (7.97ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (8.49ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (21.02ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (21.23ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (21.25ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (20.36ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (20.43ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (20.35ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (20.34ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (20.45ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (20.43ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (20.45ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (20.47ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (20.36ms)
[niks3-go-unit-tests] 2026-07-18 13:56:26.077 UTC [215] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.077 UTC [215] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (8.14ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (8.12ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (24.54ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (8.67ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (8.95ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (8.73ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (8.76ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (8.91ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (8.8ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (8.68ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (8.88ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (8.86ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (12.93ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (8.97ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (9.03ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (9.01ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] --- PASS: TestService_healthCheckHandler (0.81s)
[niks3-go-unit-tests] === CONT  TestReadProxyConditionalGet
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (8.57ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (8.72ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (8.75ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (9ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (9.09ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (9.03ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (9.11ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (9.17ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (8.99ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] --- PASS: TestReadProxyInvalidPath (0.82s)
[niks3-go-unit-tests] === CONT  TestService_createPendingClosureHandler
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (8.92ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (8.91ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (12.7ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 WARN Authentication failed token_preview=wrongtoken token_length=10 reason="static API token mismatch"
[niks3-go-unit-tests] --- PASS: TestService_AuthMiddleware (0.83s)
[niks3-go-unit-tests] === CONT  TestCreatePendingClosure_SmallNARUsesSimplePUT
[niks3-go-unit-tests] 2026/07/18 13:56:26 WARN mTLS auth: subject not in bound subjects subject="CN=reader"
[niks3-go-unit-tests] 2026/07/18 13:56:26 WARN mTLS auth: subject not in bound subjects subject="CN=writer"
[niks3-go-unit-tests] --- PASS: TestService_NativeMTLS (0.82s)
[niks3-go-unit-tests] === CONT  TestCompleteMultipartUnregistered
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (13.23ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (13.59ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (13.26ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (13.22ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (13.22ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (13.7ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (13.23ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (13.28ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (15.79ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (17.75ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (17.76ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20260628120000_add_object_size_and_stats.sql (17.71ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   1_commit_pending_closure.sql (7.38ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   2_object_stats_trigger.sql (8.56ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 goose: up to current file version: 2
[niks3-go-unit-tests] --- PASS: TestReadProxyDisabled (0.92s)
[niks3-go-unit-tests] === CONT  TestService_verifyS3Integrity
[niks3-go-unit-tests] --- PASS: TestReadProxy404 (0.92s)
[niks3-go-unit-tests] === CONT  TestReadProxyNarinfo
[niks3-go-unit-tests] {"timestamp":"2026-07-18T13:56:26.194877801Z","level":"ERROR","fields":{"message":"system path read failed","path_kind":"data_usage","operation":"read_primary","reason":"config_not_found","object":"buckets/.usage.json","error":"Config not found"},"target":"rustfs_ecstore::data_usage","filename":"crates/ecstore/src/data_usage.rs","line_number":126,"threadName":"rustfs-worker","threadId":"ThreadId(27)"}
[niks3-go-unit-tests] --- PASS: TestReadProxyRootRedirectsToIndexHTML (0.85s)
[niks3-go-unit-tests] === CONT  TestService_Rustfstest
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Created nix-cache-info in bucket bucket=bucket8
[niks3-go-unit-tests] {"timestamp":"2026-07-18T13:56:26.196592392Z","level":"ERROR","fields":{"message":"system path read failed","path_kind":"data_usage","operation":"read_primary","reason":"config_not_found","object":"buckets/.usage.json","error":"Config not found"},"target":"rustfs_ecstore::data_usage","filename":"crates/ecstore/src/data_usage.rs","line_number":126,"threadName":"rustfs-worker","threadId":"ThreadId(27)"}
[niks3-go-unit-tests] --- PASS: TestReadProxyHead (0.92s)
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/narinfo
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/narinfo
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/nar_zst
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/nar_zst
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/nar_xz
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/nar_xz
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/nar_plain
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/nar_plain
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/listing
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/listing
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/build_log
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/build_log
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/build_log_home-manager_file
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/build_log_home-manager_file
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/build_log_plus_in_name
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/build_log_plus_in_name
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/build_log_question_mark
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/build_log_question_mark
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/build_log_equals
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/build_log_equals
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/realisation
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/realisation
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/realisation_plus_in_output
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/realisation_plus_in_output
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/nix-cache-info
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/nix-cache-info
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/index.html
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/index.html
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/narinfo_key,_nar_type
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/narinfo_key,_nar_type
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/nar_key,_narinfo_type
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/nar_key,_narinfo_type
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/listing_key,_narinfo_type
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/listing_key,_narinfo_type
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/traversal
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/traversal
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/traversal_nar
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/traversal_nar
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/absolute
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/absolute
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/empty_key
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/empty_key
[niks3-go-unit-tests] === RUN   TestIsValidUploadKey/unknown_type
[niks3-go-unit-tests] === PAUSE TestIsValidUploadKey/unknown_type
[niks3-go-unit-tests] === CONT  TestProxyWriteTimeout
[niks3-go-unit-tests] === RUN   TestProxyWriteTimeout/narinfo
[niks3-go-unit-tests] === PAUSE TestProxyWriteTimeout/narinfo
[niks3-go-unit-tests] === RUN   TestProxyWriteTimeout/1_GiB_nar
[niks3-go-unit-tests] === PAUSE TestProxyWriteTimeout/1_GiB_nar
[niks3-go-unit-tests] === RUN   TestProxyWriteTimeout/10_GiB_nar
[niks3-go-unit-tests] === PAUSE TestProxyWriteTimeout/10_GiB_nar
[niks3-go-unit-tests] === RUN   TestProxyWriteTimeout/unknown_size
[niks3-go-unit-tests] === PAUSE TestProxyWriteTimeout/unknown_size
[niks3-go-unit-tests] === CONT  TestService_cleanupPendingClosuresHandler
[niks3-go-unit-tests] --- PASS: TestReadProxyNarStreaming (0.92s)
[niks3-go-unit-tests] === CONT  TestUploadHandlersRejectOversizedBody
[niks3-go-unit-tests] {"timestamp":"2026-07-18T13:56:26.199533384Z","level":"ERROR","fields":{"message":"system path read failed","path_kind":"data_usage","operation":"read_primary","reason":"config_not_found","object":"buckets/.usage.json","error":"Config not found"},"target":"rustfs_ecstore::data_usage","filename":"crates/ecstore/src/data_usage.rs","line_number":126,"threadName":"rustfs-worker","threadId":"ThreadId(23)"}
[niks3-go-unit-tests] --- PASS: TestReadProxyNarinfoAlreadyDecompressed (0.98s)
[niks3-go-unit-tests] === CONT  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle
[niks3-go-unit-tests] --- PASS: TestMetricsInventory (1.02s)
[niks3-go-unit-tests] === CONT  TestClientErrorHandling
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Received cleanup request method=DELETE path=/api/pending_closures
[niks3-go-unit-tests] === RUN   TestClientErrorHandling/InvalidStorePath
[niks3-go-unit-tests] === PAUSE TestClientErrorHandling/InvalidStorePath
[niks3-go-unit-tests] === RUN   TestClientErrorHandling/InvalidAuthToken
[niks3-go-unit-tests] === PAUSE TestClientErrorHandling/InvalidAuthToken
[niks3-go-unit-tests] === RUN   TestClientErrorHandling/ServerNotAvailable
[niks3-go-unit-tests] === PAUSE TestClientErrorHandling/ServerNotAvailable
[niks3-go-unit-tests] === CONT  TestGCMetrics
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Aborted multipart uploads count=1
[niks3-go-unit-tests] --- PASS: TestMultipartCleanup (1.04s)
[niks3-go-unit-tests] === CONT  TestGCBugBareHashReferences
[niks3-go-unit-tests] === RUN   TestUploadHandlersRejectOversizedBody/create_pending_closure
[niks3-go-unit-tests] === PAUSE TestUploadHandlersRejectOversizedBody/create_pending_closure
[niks3-go-unit-tests] === RUN   TestUploadHandlersRejectOversizedBody/complete_multipart
[niks3-go-unit-tests] === PAUSE TestUploadHandlersRejectOversizedBody/complete_multipart
[niks3-go-unit-tests] === RUN   TestUploadHandlersRejectOversizedBody/request_more_parts
[niks3-go-unit-tests] === PAUSE TestUploadHandlersRejectOversizedBody/request_more_parts
[niks3-go-unit-tests] === CONT  TestOrphanedObjectsGC
[niks3-go-unit-tests] --- PASS: TestResurrectedObjectNotDeleted (1.05s)
[niks3-go-unit-tests] === CONT  TestPinProtectsFromGC
[niks3-go-unit-tests] === NAME  TestNARDeduplicationMetadataUploadBug
[niks3-go-unit-tests]     metadata_upload_test.go:48: First store path: /build/TestNARDeduplicationMetadataUploadBug1522586550/001/store/vlg8s95820243p8al9nc3r31zh40dkcc-file1.txt
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Uploading vlg8s95820243p8al9nc3r31zh40dkcc-file1.txt (160B)
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Signed narinfos id=1 count=1
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Uploading 1 narinfos
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Completed upload id=1
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Upload complete. (128ms)
[niks3-go-unit-tests]     metadata_upload_test.go:54: Retrieved narinfo from S3:
[niks3-go-unit-tests]         StorePath: /build/TestNARDeduplicationMetadataUploadBug1522586550/001/store/vlg8s95820243p8al9nc3r31zh40dkcc-file1.txt
[niks3-go-unit-tests]         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst
[niks3-go-unit-tests]         Compression: zstd
[niks3-go-unit-tests]         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf
[niks3-go-unit-tests]         NarSize: 160
[niks3-go-unit-tests]         References: 
[niks3-go-unit-tests]         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf
[niks3-go-unit-tests]     metadata_upload_test.go:55: Retrieved .ls file from S3 (compressed size: 77 bytes)
[niks3-go-unit-tests]     metadata_upload_test.go:55: Decompressed .ls content (64 bytes):
[niks3-go-unit-tests]         {"version":1,"root":{"type":"regular","size":44,"narOffset":96}}
[niks3-go-unit-tests]     metadata_upload_test.go:64: Second store path (same content): /build/TestNARDeduplicationMetadataUploadBug1522586550/001/store/i0s064cwvyfh7q0wrdzyf9b5zpikvbik-file2.txt
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Uploading 0 paths to 127.0.0.1 (1 already cached)
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Signed narinfos id=2 count=1
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Uploading 1 narinfos
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Completed upload id=2
[niks3-go-unit-tests] 2026/07/18 13:56:26 INFO Upload complete. (56ms)
[niks3-go-unit-tests]     metadata_upload_test.go:76: Retrieved narinfo from S3:
[niks3-go-unit-tests]         StorePath: /build/TestNARDeduplicationMetadataUploadBug1522586550/001/store/i0s064cwvyfh7q0wrdzyf9b5zpikvbik-file2.txt
[niks3-go-unit-tests]         URL: nar/1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf.nar.zst
[niks3-go-unit-tests]         Compression: zstd
[niks3-go-unit-tests]         NarHash: sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf
[niks3-go-unit-tests]         NarSize: 160
[niks3-go-unit-tests]         References: 
[niks3-go-unit-tests]         CA: fixed:r:sha256:1byf36hhc5sn8lvwqfkjh3ybgn76slmx8naakslrypxs6mna71rf
[niks3-go-unit-tests]     metadata_upload_test.go:77: Retrieved .ls file from S3 (compressed size: 62 bytes)
[niks3-go-unit-tests]     metadata_upload_test.go:77: Decompressed .ls content (49 bytes):
[niks3-go-unit-tests]         {"version":1,"root":{"type":"regular","size":44}}
[niks3-go-unit-tests] --- PASS: TestNARDeduplicationMetadataUploadBug (1.51s)
[niks3-go-unit-tests] === CONT  TestOrphanedObjectsGCStressTest
[niks3-go-unit-tests] 2026-07-18 13:56:26.796 UTC [288] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.796 UTC [288] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.796 UTC [290] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.796 UTC [290] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.797 UTC [287] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.797 UTC [287] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:26.798 UTC [289] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:26.798 UTC [289] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (19.26ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (76.99ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (35.15ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (18.31ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20241026095416_initial_model.sql (39.9ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (25.35ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (23.96ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251210153512_drop_unused_gin_index.sql (19.27ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (81.33ms)
[niks3-go-unit-tests] 2026/07/18 13:56:26 OK   20251218171726_add_pins.sql (81.15ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (77.25ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (12.86ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (27.38ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (84.81ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (6.14ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (12.06ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (19.81ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (12.1ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (5.67ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (56ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (56.12ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (62.24ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (54.99ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026-07-18 13:56:27.085 UTC [389] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.085 UTC [389] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:27.085 UTC [390] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.085 UTC [390] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:27.085 UTC [388] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.085 UTC [388] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:27.088 UTC [391] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.088 UTC [391] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (11.09ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 ERROR Failed to get multipart upload error="no rows in result set" upload_id=does-not-exist object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst
[niks3-go-unit-tests] --- PASS: TestCompleteMultipartUnregistered (0.99s)
[niks3-go-unit-tests] === CONT  TestClientWithDependencies
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] --- PASS: TestReadProxyConditionalGet (1.00s)
[niks3-go-unit-tests] === CONT  TestObjectStatsTrigger
[niks3-go-unit-tests] 2026-07-18 13:56:27.093 UTC [392] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.093 UTC [392] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:27.094 UTC [393] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.094 UTC [393] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:27.094 UTC [397] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.094 UTC [397] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:27.094 UTC [396] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.094 UTC [396] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:27.096 UTC [395] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.096 UTC [395] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (16.33ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (16.3ms)
[niks3-go-unit-tests] === CONT  TestClientMultipleUploads
[niks3-go-unit-tests] --- PASS: TestCreatePendingClosure_SmallNARUsesSimplePUT (1.01s)
[niks3-go-unit-tests] 2026-07-18 13:56:27.117 UTC [402] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.117 UTC [402] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (14.77ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (14.75ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (14.99ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (14.69ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (14.69ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (15.35ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (15.43ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (20.78ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (22.14ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (15.23ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (14.83ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (20.13ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (22.42ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (22.23ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (22.75ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (19.7ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (22.92ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (22.63ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (23.02ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (21.34ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (23.2ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (15.27ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (15.26ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (15.43ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (15.23ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (15.34ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (16.47ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (16.37ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (16.37ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (16.35ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (16.53ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (17.47ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (17.57ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (17.58ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (17.5ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (17.48ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (17.21ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (17.16ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (17.27ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (17.21ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (17.26ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (15.74ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (15.99ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (15.81ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (15.96ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (15.84ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (16.27ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (16.32ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (16.42ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (16.39ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (16.37ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received cleanup request method=DELETE path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Aborted multipart uploads count=0
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (17.65ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (17.65ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (17.63ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] --- PASS: TestReadProxyNarinfo (1.02s)
[niks3-go-unit-tests] === CONT  TestRedundantMultipartUpload
[niks3-go-unit-tests] --- PASS: TestService_Rustfstest (1.02s)
[niks3-go-unit-tests] === CONT  TestClientIntegration
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Created nix-cache-info in bucket bucket=bucket26
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (13.1ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (13.27ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (13.27ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (13.23ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received cleanup request method=DELETE path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Aborted multipart uploads count=1
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
[niks3-go-unit-tests] 2026-07-18 13:56:27.251 UTC [391] ERROR:  Closure does not exist: id=1
[niks3-go-unit-tests] 2026-07-18 13:56:27.251 UTC [391] CONTEXT:  PL/pgSQL function commit_pending_closure(bigint) line 16 at RAISE
[niks3-go-unit-tests] 2026-07-18 13:56:27.251 UTC [391] STATEMENT:  -- name: CommitPendingClosure :exec
[niks3-go-unit-tests] 	SELECT commit_pending_closure($1::bigint)
[niks3-go-unit-tests] 	
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
[niks3-go-unit-tests] --- PASS: TestService_cleanupPendingClosuresHandler (1.05s)
[niks3-go-unit-tests] === CONT  TestCompleteMultipartUpload_ErrorButObjectExists
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Completed multipart upload object_key=nar/0000000000000000000000000000000000000000000000000000.nar.zst upload_id=YTNlYzZhMzMtZmM3OC00ZmFiLTllMTQtYTBiZmQ5MTAxZGJkLmZiZTc0NzFlLWQyMjMtNDc0My1iMmFiLTIyMTQxNTFlNDJhMngxNzg0MzgyOTg3MTA0ODM1MDg2 parts=10
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Completed upload id=1
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Starting cleanup of old closures method=DELETE path=/api/closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Aborted multipart uploads count=0
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Aborted multipart uploads count=0
[niks3-go-unit-tests] 2026/07/18 13:56:27 WARN Force mode enabled - objects will be deleted immediately without grace period
[niks3-go-unit-tests] 2026/07/18 13:56:27 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=0
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Vacuumed table table=pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Vacuumed table table=pending_objects
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Vacuumed table table=multipart_uploads
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Vacuumed table table=closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Vacuumed table table=objects
[niks3-go-unit-tests] --- PASS: TestGCMetrics (1.06s)
[niks3-go-unit-tests] === CONT  TestReadProxyRangeRequest
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Completed multipart upload object_key=nar/dadb44fdadb44fdadb44fdadb44f000000000000000000000000.nar.zst upload_id=YTNlYzZhMzMtZmM3OC00ZmFiLTllMTQtYTBiZmQ5MTAxZGJkLmUxY2UxOTJhLTI5MmUtNDI5Yy05NTg5LTk0NGU1NjAxYTgwMHgxNzg0MzgyOTg3MjM1MzIyMzkz parts=10
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=2 objects-deleted-after-grace-period=0 objects-failed-to-delete=0
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Completed upload id=1
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Vacuumed table table=pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Vacuumed table table=pending_objects
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Object in database but missing from S3 key=dadb44fdadb44fdadb44fdadb44f0000.narinfo
[niks3-go-unit-tests] 2026/07/18 13:56:27 WARN Found objects in DB but missing from S3, will re-upload count=1
[niks3-go-unit-tests] --- PASS: TestService_verifyS3Integrity (1.29s)
[niks3-go-unit-tests] === CONT  TestService_AuthMiddleware_OIDC
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO OIDC provider initialized name=test
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Vacuumed table table=multipart_uploads
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Vacuumed table table=closures
[niks3-go-unit-tests] === NAME  TestPinProtectsFromGC
[niks3-go-unit-tests]     client_integration_test.go:646: Pinned store path: /build/TestPinProtectsFromGC1671256906/001/store/x3s432kl8hk7y8igx2dqfdfwavp1y6z3-pinned-file.txt
[niks3-go-unit-tests]     client_integration_test.go:647: Unpinned store path: /build/TestPinProtectsFromGC1671256906/001/store/c3zn189nj5c95y46l3dk6g1g2xkhks2j-unpinned-file.txt
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Vacuumed table table=objects
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received get closure request method=GET path=/api/closures/00000000000000000000000000000000
[niks3-go-unit-tests] --- PASS: TestGCBugBareHashReferences (1.28s)
[niks3-go-unit-tests] === CONT  TestService_AuthMiddleware_MTLSBoundSubjects
[niks3-go-unit-tests] --- PASS: TestService_createPendingClosureHandler (1.50s)
[niks3-go-unit-tests] === CONT  TestClientCADerivations
[niks3-go-unit-tests] 2026-07-18 13:56:27.599 UTC [432] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.599 UTC [432] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:27.600 UTC [447] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.600 UTC [447] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:27.601 UTC [433] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:27.601 UTC [433] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (27.3ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (34.38ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20241026095416_initial_model.sql (34.39ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (14.63ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (16.66ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251210153512_drop_unused_gin_index.sql (16.7ms)
[niks3-go-unit-tests] === NAME  TestOrphanedObjectsGC
[niks3-go-unit-tests]     orphaned_objects_gc_test.go:290: GC Test Summary:
[niks3-go-unit-tests]     orphaned_objects_gc_test.go:291:   - Kept:    2 objects from closure A
[niks3-go-unit-tests]     orphaned_objects_gc_test.go:292:   - Deleted: 2 objects from closure B
[niks3-go-unit-tests]     orphaned_objects_gc_test.go:293:   - Deleted: 6 orphaned chain objects (X1->X2->X3)
[niks3-go-unit-tests]     orphaned_objects_gc_test.go:294:   - Deleted: 2 orphaned single objects (Y)
[niks3-go-unit-tests]     orphaned_objects_gc_test.go:295:   - Total deleted: 10 objects
[niks3-go-unit-tests] --- PASS: TestOrphanedObjectsGC (1.35s)
[niks3-go-unit-tests] === CONT  TestService_ReadAuthMiddleware
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (17.51ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (16.28ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20251218171726_add_pins.sql (16.38ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (17.45ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (24.55ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   20260628120000_add_object_size_and_stats.sql (24.54ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (22.32ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (14.79ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   1_commit_pending_closure.sql (14.97ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (16.88ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (18.17ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 OK   2_object_stats_trigger.sql (18.3ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Created nix-cache-info in bucket bucket=bucket31
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Created nix-cache-info in bucket bucket=bucket32
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Uploading x3s432kl8hk7y8igx2dqfdfwavp1y6z3-pinned-file.txt (128B)
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Signed narinfos id=1 count=1
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Uploading 1 narinfos
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Completed upload id=1
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Upload complete. (303ms)
[niks3-go-unit-tests] 2026/07/18 13:56:27 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Uploading c3zn189nj5c95y46l3dk6g1g2xkhks2j-unpinned-file.txt (128B)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Signed narinfos id=2 count=1
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Uploading 1 narinfos
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete
[niks3-go-unit-tests] --- PASS: TestObjectStatsTrigger (1.09s)
[niks3-go-unit-tests] === CONT  TestCacheStatsHandler
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Completed upload id=2
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Upload complete. (283ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received create pin request method=POST path=/api/pins/myapp
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Created/updated pin name=myapp store_path=/build/TestPinProtectsFromGC1671256906/001/store/x3s432kl8hk7y8igx2dqfdfwavp1y6z3-pinned-file.txt narinfo_key=x3s432kl8hk7y8igx2dqfdfwavp1y6z3.narinfo
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Starting cleanup of old closures method=DELETE path=/api/closures
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Garbage collection started
[niks3-go-unit-tests] 2026-07-18 13:56:28.285 UTC [503] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:28.285 UTC [503] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:28.285 UTC [502] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:28.285 UTC [502] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] === NAME  TestClientMultipleUploads
[niks3-go-unit-tests]     client_integration_test.go:338: Created store path 0: /build/TestClientMultipleUploads193067892/001/store/564m4bzhdm95mwxi5s5a6mby8x2sm29l-test-file-0.txt
[niks3-go-unit-tests] === NAME  TestClientWithDependencies
[niks3-go-unit-tests]     client_integration_test.go:593: Built derivation: /build/TestClientWithDependencies600145612/001/store/cvily1kjgarp703f05gk8z8i50vi8jcq-test-script
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Aborted multipart uploads count=0
[niks3-go-unit-tests]     client_integration_test.go:595: Found 1 dependencies (including self)
[niks3-go-unit-tests] 2026/07/18 13:56:28 WARN Force mode enabled - objects will be deleted immediately without grace period
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20241026095416_initial_model.sql (45.69ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20241026095416_initial_model.sql (45.59ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Uploading cvily1kjgarp703f05gk8z8i50vi8jcq-test-script (136B)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Signed narinfos id=1 count=1
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Uploading 1 narinfos
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
[niks3-go-unit-tests] === NAME  TestClientMultipleUploads
[niks3-go-unit-tests]     client_integration_test.go:338: Created store path 1: /build/TestClientMultipleUploads193067892/001/store/3m1l5k0v2xj36l05wpcar57pi6mcj7rh-test-file-1.txt
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251210153512_drop_unused_gin_index.sql (20.75ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251210153512_drop_unused_gin_index.sql (20.75ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251218171726_add_pins.sql (10.68ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Completed upload id=1
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251218171726_add_pins.sql (10.67ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Upload complete. (66ms)
[niks3-go-unit-tests] === NAME  TestClientWithDependencies
[niks3-go-unit-tests]     client_integration_test.go:597: Skipping nix copy test - isolated store (/build/TestClientWithDependencies600145612/001/store) requires matching store prefix
[niks3-go-unit-tests] --- PASS: TestClientWithDependencies (1.38s)
[niks3-go-unit-tests] === CONT  TestService_AuthMiddleware_MTLSProxyHeader
[niks3-go-unit-tests] === NAME  TestClientMultipleUploads
[niks3-go-unit-tests]     client_integration_test.go:338: Created store path 2: /build/TestClientMultipleUploads193067892/001/store/xkdh2c6fwh7y7wi9fr26qlhd7980a10z-test-file-2.txt
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20260628120000_add_object_size_and_stats.sql (106.26ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20260628120000_add_object_size_and_stats.sql (106.22ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   1_commit_pending_closure.sql (17.36ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   1_commit_pending_closure.sql (17.45ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   2_object_stats_trigger.sql (14.63ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   2_object_stats_trigger.sql (14.58ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Created nix-cache-info in bucket bucket=bucket33
[niks3-go-unit-tests] 2026-07-18 13:56:28.635 UTC [701] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:28.635 UTC [701] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:28.638 UTC [719] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:28.638 UTC [719] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:28.638 UTC [702] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:28.638 UTC [702] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:28.638 UTC [700] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:28.638 UTC [700] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:28.638 UTC [683] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:28.638 UTC [683] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
[niks3-go-unit-tests] {"timestamp":"2026-07-18T13:56:28.661522321Z","level":"ERROR","fields":{"message":"complete_multipart_upload part error: \"part.1 not found\""},"target":"rustfs_ecstore::set_disk","filename":"crates/ecstore/src/set_disk.rs","line_number":3463,"threadName":"rustfs-worker","threadId":"ThreadId(27)"}
[niks3-go-unit-tests] {"timestamp":"2026-07-18T13:56:28.661544951Z","level":"ERROR","fields":{"message":"complete_multipart_upload etag err client=Some(\"deadbeef\"), stored=\"\", part_id=1, bucket=bucket34, object=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst"},"target":"rustfs_ecstore::set_disk","filename":"crates/ecstore/src/set_disk.rs","line_number":3534,"threadName":"rustfs-worker","threadId":"ThreadId(27)"}
[niks3-go-unit-tests] 2026/07/18 13:56:28 WARN CompleteMultipartUpload errored but object exists; treating as success error="One or more of the specified parts could not be found.  The part may not have been uploaded, or the specified entity tag may not match the part's entity tag." object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=YTNlYzZhMzMtZmM3OC00ZmFiLTllMTQtYTBiZmQ5MTAxZGJkLjJkMmIzY2RlLWVkZjgtNDQ0My05MTQ3LTVmMWY0NzA4OTM2MngxNzg0MzgyOTg4NjI4MDg2MjEy
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0300000000000000000000.nar.zst upload_id=YTNlYzZhMzMtZmM3OC00ZmFiLTllMTQtYTBiZmQ5MTAxZGJkLjJkMmIzY2RlLWVkZjgtNDQ0My05MTQ3LTVmMWY0NzA4OTM2MngxNzg0MzgyOTg4NjI4MDg2MjEy parts=1
[niks3-go-unit-tests] --- PASS: TestCompleteMultipartUpload_ErrorButObjectExists (1.43s)
[niks3-go-unit-tests] === CONT  TestCacheConfigHandler
[niks3-go-unit-tests] === RUN   TestCacheConfigHandler/full_config,_no_issuer
[niks3-go-unit-tests] === PAUSE TestCacheConfigHandler/full_config,_no_issuer
[niks3-go-unit-tests] === RUN   TestCacheConfigHandler/no_cache_url_configured
[niks3-go-unit-tests] === PAUSE TestCacheConfigHandler/no_cache_url_configured
[niks3-go-unit-tests] === RUN   TestCacheConfigHandler/no_signing_keys
[niks3-go-unit-tests] === PAUSE TestCacheConfigHandler/no_signing_keys
[niks3-go-unit-tests] === RUN   TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator
[niks3-go-unit-tests] === PAUSE TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator
[niks3-go-unit-tests] === CONT  TestIsValidCachePath
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/narinfo
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/narinfo
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/narinfo_all_nix_base32_chars
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/narinfo_all_nix_base32_chars
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/nar_zst
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/nar_zst
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/nar_xz
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/nar_xz
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/nar_bz2
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/nar_bz2
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/nar_uncompressed
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/nar_uncompressed
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/ls
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/ls
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/log
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/log
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/realisation
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/realisation
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/nix-cache-info
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/nix-cache-info
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/index.html
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/index.html
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/traversal_parent
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/traversal_parent
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/traversal_in_middle
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/traversal_in_middle
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/invalid_char_e
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/invalid_char_e
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/invalid_char_u
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/invalid_char_u
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/random_path
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/random_path
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/empty
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/empty
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/leading_slash
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/leading_slash
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/wrong_extension
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/wrong_extension
[niks3-go-unit-tests] === RUN   TestIsValidCachePath/short_hash
[niks3-go-unit-tests] === PAUSE TestIsValidCachePath/short_hash
[niks3-go-unit-tests] === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received uploads request method=POST path=/
[niks3-go-unit-tests] === CONT  TestServerTLSConfig/no_client_CA
[niks3-go-unit-tests] === CONT  TestServerTLSConfig/not_a_PEM_file
[niks3-go-unit-tests] === CONT  TestServerTLSConfig/missing_CA_file
[niks3-go-unit-tests] --- PASS: TestServerTLSConfig (0.00s)
[niks3-go-unit-tests]     --- PASS: TestServerTLSConfig/no_client_CA (0.00s)
[niks3-go-unit-tests]     --- PASS: TestServerTLSConfig/not_a_PEM_file (0.00s)
[niks3-go-unit-tests]     --- PASS: TestServerTLSConfig/missing_CA_file (0.00s)
[niks3-go-unit-tests] === CONT  TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received complete multipart upload request method=POST path=/
[niks3-go-unit-tests] === CONT  TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received request for more parts method=POST path=/
[niks3-go-unit-tests] === CONT  TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received uploads request method=POST path=/
[niks3-go-unit-tests] --- PASS: TestUploadHandlersRejectInvalidKeys (0.00s)
[niks3-go-unit-tests]     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_nix-cache-info (0.00s)
[niks3-go-unit-tests]     --- PASS: TestUploadHandlersRejectInvalidKeys/complete_multipart_rejects_non-NAR_key (0.00s)
[niks3-go-unit-tests]     --- PASS: TestUploadHandlersRejectInvalidKeys/request_more_parts_rejects_non-NAR_key (0.00s)
[niks3-go-unit-tests]     --- PASS: TestUploadHandlersRejectInvalidKeys/create_pending_closure_rejects_path_traversal (0.00s)
[niks3-go-unit-tests] === CONT  TestParseSingleRange/none
[niks3-go-unit-tests] === CONT  TestParseSingleRange/open-ended
[niks3-go-unit-tests] === CONT  TestParseSingleRange/start_far_past_EOF
[niks3-go-unit-tests] === CONT  TestParseSingleRange/start_past_EOF
[niks3-go-unit-tests] === CONT  TestParseSingleRange/single_byte
[niks3-go-unit-tests] === CONT  TestParseSingleRange/suffix_exceeds_size
[niks3-go-unit-tests] === CONT  TestParseSingleRange/suffix
[niks3-go-unit-tests] === CONT  TestParseSingleRange/end_clamped_to_size
[niks3-go-unit-tests] === CONT  TestParseSingleRange/malformed_both_empty
[niks3-go-unit-tests] === CONT  TestParseSingleRange/closed
[niks3-go-unit-tests] === CONT  TestParseSingleRange/malformed_end_before_start
[niks3-go-unit-tests] === CONT  TestParseSingleRange/multi-range_ignored
[niks3-go-unit-tests] === CONT  TestParseSingleRange/malformed_no_dash
[niks3-go-unit-tests] === CONT  TestParseSingleRange/unknown_unit
[niks3-go-unit-tests] --- PASS: TestParseSingleRange (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/none (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/open-ended (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/start_far_past_EOF (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/start_past_EOF (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/single_byte (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/suffix_exceeds_size (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/suffix (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/end_clamped_to_size (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/malformed_both_empty (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/closed (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/malformed_end_before_start (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/multi-range_ignored (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/malformed_no_dash (0.00s)
[niks3-go-unit-tests]     --- PASS: TestParseSingleRange/unknown_unit (0.00s)
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/narinfo
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/realisation_plus_in_output
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/unknown_type
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/empty_key
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/absolute
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/traversal_nar
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/traversal
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/listing_key,_narinfo_type
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/nar_key,_narinfo_type
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/narinfo_key,_nar_type
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/index.html
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/nix-cache-info
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/build_log_home-manager_file
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/realisation
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/build_log_equals
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/build_log_question_mark
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/build_log_plus_in_name
[niks3-go-unit-tests] === CONT  TestProxyWriteTimeout/narinfo
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/nar_zst
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/build_log
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/listing
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/nar_xz
[niks3-go-unit-tests] === CONT  TestProxyWriteTimeout/10_GiB_nar
[niks3-go-unit-tests] === CONT  TestProxyWriteTimeout/unknown_size
[niks3-go-unit-tests] === CONT  TestProxyWriteTimeout/1_GiB_nar
[niks3-go-unit-tests] --- PASS: TestProxyWriteTimeout (0.00s)
[niks3-go-unit-tests]     --- PASS: TestProxyWriteTimeout/narinfo (0.00s)
[niks3-go-unit-tests]     --- PASS: TestProxyWriteTimeout/10_GiB_nar (0.00s)
[niks3-go-unit-tests]     --- PASS: TestProxyWriteTimeout/unknown_size (0.00s)
[niks3-go-unit-tests]     --- PASS: TestProxyWriteTimeout/1_GiB_nar (0.00s)
[niks3-go-unit-tests] === CONT  TestIsValidUploadKey/nar_plain
[niks3-go-unit-tests] --- PASS: TestIsValidUploadKey (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/narinfo (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/realisation_plus_in_output (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/unknown_type (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/empty_key (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/absolute (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/traversal_nar (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/traversal (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/listing_key,_narinfo_type (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/nar_key,_narinfo_type (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/narinfo_key,_nar_type (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/index.html (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/nix-cache-info (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/build_log_home-manager_file (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/realisation (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/build_log_equals (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/build_log_question_mark (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/build_log_plus_in_name (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/nar_zst (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/build_log (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/listing (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/nar_xz (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidUploadKey/nar_plain (0.00s)
[niks3-go-unit-tests] === CONT  TestClientErrorHandling/InvalidStorePath
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20241026095416_initial_model.sql (24.82ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20241026095416_initial_model.sql (24.74ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20241026095416_initial_model.sql (24.93ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20241026095416_initial_model.sql (40.9ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251210153512_drop_unused_gin_index.sql (16.27ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20241026095416_initial_model.sql (41.16ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251210153512_drop_unused_gin_index.sql (16.27ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251210153512_drop_unused_gin_index.sql (16.36ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251218171726_add_pins.sql (15.94ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251218171726_add_pins.sql (15.99ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251218171726_add_pins.sql (15.96ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251210153512_drop_unused_gin_index.sql (16.21ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251210153512_drop_unused_gin_index.sql (16.14ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251218171726_add_pins.sql (17.44ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20260628120000_add_object_size_and_stats.sql (17.58ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20260628120000_add_object_size_and_stats.sql (17.54ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20260628120000_add_object_size_and_stats.sql (17.54ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251218171726_add_pins.sql (17.43ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=2001 objects-failed-to-delete=0
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   1_commit_pending_closure.sql (14.28ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20260628120000_add_object_size_and_stats.sql (14.29ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   1_commit_pending_closure.sql (14.31ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20260628120000_add_object_size_and_stats.sql (14.38ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   1_commit_pending_closure.sql (14.23ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   1_commit_pending_closure.sql (12.22ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   1_commit_pending_closure.sql (12.18ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   2_object_stats_trigger.sql (12.27ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   2_object_stats_trigger.sql (12.21ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   2_object_stats_trigger.sql (12.24ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Vacuumed table table=pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:28 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted,O=Other"
[niks3-go-unit-tests] 2026/07/18 13:56:28 WARN mTLS auth: bound subjects configured but subject DN unavailable
[niks3-go-unit-tests] 2026/07/18 13:56:28 WARN mTLS auth: subject not in bound subjects subject="CN=untrusted"
[niks3-go-unit-tests] --- PASS: TestService_AuthMiddleware_MTLSBoundSubjects (1.17s)
[niks3-go-unit-tests] === CONT  TestClientErrorHandling/ServerNotAvailable
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Created nix-cache-info in bucket bucket=bucket36
[niks3-go-unit-tests] --- PASS: TestReadProxyRangeRequest (1.42s)
[niks3-go-unit-tests] === CONT  TestClientErrorHandling/InvalidAuthToken
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   2_object_stats_trigger.sql (18.66ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   2_object_stats_trigger.sql (18.69ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] === RUN   TestService_AuthMiddleware_OIDC/valid_OIDC_token
[niks3-go-unit-tests] === PAUSE TestService_AuthMiddleware_OIDC/valid_OIDC_token
[niks3-go-unit-tests] === RUN   TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected
[niks3-go-unit-tests] === PAUSE TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected
[niks3-go-unit-tests] === RUN   TestService_AuthMiddleware_OIDC/malformed_token_rejected
[niks3-go-unit-tests] === PAUSE TestService_AuthMiddleware_OIDC/malformed_token_rejected
[niks3-go-unit-tests] === RUN   TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured
[niks3-go-unit-tests] === PAUSE TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured
[niks3-go-unit-tests] === CONT  TestUploadHandlersRejectOversizedBody/create_pending_closure
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received uploads request method=POST path=/
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Uploading 3 paths to 127.0.0.1 (0 already cached)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Uploading 564m4bzhdm95mwxi5s5a6mby8x2sm29l-test-file-0.txt (160B)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Uploading 3m1l5k0v2xj36l05wpcar57pi6mcj7rh-test-file-1.txt (160B)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Uploading xkdh2c6fwh7y7wi9fr26qlhd7980a10z-test-file-2.txt (160B)
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Signed narinfos id=1 count=1
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Signed narinfos id=2 count=1
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Signed narinfos id=3 count=1
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Uploading 3 narinfos
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Vacuumed table table=pending_objects
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Vacuumed table table=multipart_uploads
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Completed upload id=2
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Vacuumed table table=closures
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Completed upload id=3
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Vacuumed table table=objects
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Completed upload id=1
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Upload complete. (253ms)
[niks3-go-unit-tests] === NAME  TestClientMultipleUploads
[niks3-go-unit-tests]     client_integration_test.go:349: Uploaded 3 paths in 274.628262ms
[niks3-go-unit-tests] --- PASS: TestClientMultipleUploads (1.73s)
[niks3-go-unit-tests] === CONT  TestUploadHandlersRejectOversizedBody/request_more_parts
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received request for more parts method=POST path=/
[niks3-go-unit-tests] 2026-07-18 13:56:28.849 UTC [773] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:28.849 UTC [773] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] === NAME  TestClientIntegration
[niks3-go-unit-tests]     client_integration_test.go:276: Created store path: /build/TestClientIntegration9495799/002/store/r75477vassmvng1rdm64hgapzbiflwbf-test-file.txt
[niks3-go-unit-tests] 2026-07-18 13:56:28.850 UTC [774] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:28.850 UTC [774] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] === CONT  TestUploadHandlersRejectOversizedBody/complete_multipart
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO Received complete multipart upload request method=POST path=/
[niks3-go-unit-tests] === CONT  TestCacheConfigHandler/full_config,_no_issuer
[niks3-go-unit-tests] === CONT  TestCacheConfigHandler/no_signing_keys
[niks3-go-unit-tests] === CONT  TestCacheConfigHandler/no_cache_url_configured
[niks3-go-unit-tests] === CONT  TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator
[niks3-go-unit-tests] --- PASS: TestCacheConfigHandler (0.00s)
[niks3-go-unit-tests]     --- PASS: TestCacheConfigHandler/full_config,_no_issuer (0.00s)
[niks3-go-unit-tests]     --- PASS: TestCacheConfigHandler/no_signing_keys (0.00s)
[niks3-go-unit-tests]     --- PASS: TestCacheConfigHandler/no_cache_url_configured (0.00s)
[niks3-go-unit-tests]     --- PASS: TestCacheConfigHandler/issuer_requested_but_no_OIDC_validator (0.00s)
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/narinfo
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/index.html
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/nix-cache-info
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/realisation
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/log
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/ls
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/nar_uncompressed
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/nar_bz2
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/nar_xz
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/nar_zst
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/narinfo_all_nix_base32_chars
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/empty
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/traversal_parent
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/random_path
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/invalid_char_u
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/invalid_char_e
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/traversal_in_middle
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/wrong_extension
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/short_hash
[niks3-go-unit-tests] === CONT  TestIsValidCachePath/leading_slash
[niks3-go-unit-tests] --- PASS: TestIsValidCachePath (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/narinfo (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/index.html (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/nix-cache-info (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/realisation (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/log (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/ls (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/nar_uncompressed (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/nar_bz2 (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/nar_xz (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/nar_zst (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/narinfo_all_nix_base32_chars (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/empty (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/traversal_parent (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/random_path (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/invalid_char_u (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/invalid_char_e (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/traversal_in_middle (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/wrong_extension (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/short_hash (0.00s)
[niks3-go-unit-tests]     --- PASS: TestIsValidCachePath/leading_slash (0.00s)
[niks3-go-unit-tests] === CONT  TestService_AuthMiddleware_OIDC/valid_OIDC_token
[niks3-go-unit-tests] 2026/07/18 13:56:28 INFO OIDC auth successful provider=test
[niks3-go-unit-tests] === CONT  TestService_AuthMiddleware_OIDC/malformed_token_rejected
[niks3-go-unit-tests] 2026/07/18 13:56:28 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]
[niks3-go-unit-tests] === CONT  TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured
[niks3-go-unit-tests] === CONT  TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected
[niks3-go-unit-tests] 2026/07/18 13:56:28 WARN Authentication failed token_preview=eyJhbGciOi...SuoQjFj4-w token_length=702 oidc_error="bound claims validation failed: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]
[niks3-go-unit-tests] --- PASS: TestService_AuthMiddleware_OIDC (1.30s)
[niks3-go-unit-tests]     --- PASS: TestService_AuthMiddleware_OIDC/valid_OIDC_token (0.00s)
[niks3-go-unit-tests]     --- PASS: TestService_AuthMiddleware_OIDC/malformed_token_rejected (0.00s)
[niks3-go-unit-tests]     --- PASS: TestService_AuthMiddleware_OIDC/static_token_still_works_with_OIDC_configured (0.00s)
[niks3-go-unit-tests]     --- PASS: TestService_AuthMiddleware_OIDC/OIDC_token_with_wrong_org_rejected (0.00s)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20241026095416_initial_model.sql (79.26ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20241026095416_initial_model.sql (79.8ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251210153512_drop_unused_gin_index.sql (24.85ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251210153512_drop_unused_gin_index.sql (24.79ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251218171726_add_pins.sql (15.72ms)
[niks3-go-unit-tests] 2026/07/18 13:56:28 OK   20251218171726_add_pins.sql (15.77ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20260628120000_add_object_size_and_stats.sql (8.21ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20260628120000_add_object_size_and_stats.sql (8.17ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] === NAME  TestClientCADerivations
[niks3-go-unit-tests]     client_ca_test.go:136: Built CA derivation: /build/TestClientCADerivations2368718488/001/store/bk8p4k5f7lvwvf3pk5z030drm0m5xcdz-ca-test
[niks3-go-unit-tests] 2026-07-18 13:56:29.003 UTC [888] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:29.003 UTC [888] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026-07-18 13:56:29.003 UTC [887] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:29.003 UTC [887] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Received complete multipart upload request method=POST path=/api/multipart/complete
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   1_commit_pending_closure.sql (16.23ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   1_commit_pending_closure.sql (16.31ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Uploading r75477vassmvng1rdm64hgapzbiflwbf-test-file.txt (152B)
[niks3-go-unit-tests]     client_ca_test.go:139: Found 1 dependencies (including self)
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Signed narinfos id=1 count=1
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Uploading 1 narinfos
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   2_object_stats_trigger.sql (10.24ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   2_object_stats_trigger.sql (10.43ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:29 WARN mTLS auth: subject not in bound subjects subject="CN=writer"
[niks3-go-unit-tests] --- PASS: TestService_ReadAuthMiddleware (1.35s)
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Completed multipart upload object_key=nar/gggggggggggggggggggggggggggggg0100000000000000000000.nar.zst upload_id=YTNlYzZhMzMtZmM3OC00ZmFiLTllMTQtYTBiZmQ5MTAxZGJkLjAyYjc0NjNkLWE4ODUtNDJjOS1iMmJmLWYyOWM2YTYzMTEwNHgxNzg0MzgyOTg4ODA0NDc0NzI2 parts=12
[niks3-go-unit-tests] --- PASS: TestRedundantMultipartUpload (1.82s)
[niks3-go-unit-tests] 2026-07-18 13:56:29.040 UTC [937] ERROR:  relation "goose_db_version" does not exist at character 36
[niks3-go-unit-tests] 2026-07-18 13:56:29.040 UTC [937] STATEMENT:  SELECT version_id, is_applied from goose_db_version ORDER BY id DESC
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20241026095416_initial_model.sql (20.42ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20241026095416_initial_model.sql (19.9ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Completed upload id=1
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Upload complete. (171ms)
[niks3-go-unit-tests] === NAME  TestClientIntegration
[niks3-go-unit-tests]     client_integration_test.go:292: Retrieved narinfo from S3:
[niks3-go-unit-tests]         StorePath: /build/TestClientIntegration9495799/002/store/r75477vassmvng1rdm64hgapzbiflwbf-test-file.txt
[niks3-go-unit-tests]         URL: nar/11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1.nar.zst
[niks3-go-unit-tests]         Compression: zstd
[niks3-go-unit-tests]         NarHash: sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1
[niks3-go-unit-tests]         NarSize: 152
[niks3-go-unit-tests]         References: 
[niks3-go-unit-tests]         CA: fixed:r:sha256:11l1b7hsy9g9pz8i2rcn4n128l6pyr2yf58d49r12hr5caw9dzk1
[niks3-go-unit-tests]     client_integration_test.go:293: Retrieved .ls file from S3 (compressed size: 77 bytes)
[niks3-go-unit-tests]     client_integration_test.go:293: Decompressed .ls content (64 bytes):
[niks3-go-unit-tests]         {"version":1,"root":{"type":"regular","size":39,"narOffset":96}}
[niks3-go-unit-tests]     client_integration_test.go:296: Testing garbage collection...
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20251210153512_drop_unused_gin_index.sql (17.3ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20251210153512_drop_unused_gin_index.sql (15.61ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Starting cleanup of old closures method=DELETE path=/api/closures
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Garbage collection started
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Aborted multipart uploads count=0
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20241026095416_initial_model.sql (17.8ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20251218171726_add_pins.sql (16.08ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20251218171726_add_pins.sql (16.13ms)
[niks3-go-unit-tests] --- PASS: TestCacheStatsHandler (0.89s)
[niks3-go-unit-tests] 2026/07/18 13:56:29 WARN Force mode enabled - objects will be deleted immediately without grace period
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20251210153512_drop_unused_gin_index.sql (17.05ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Received uploads request method=POST path=/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:29 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_closures
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20260628120000_add_object_size_and_stats.sql (16.8ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20260628120000_add_object_size_and_stats.sql (16.84ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20251218171726_add_pins.sql (12.07ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Uploading 1 paths to 127.0.0.1 (0 already cached)
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Uploading bk8p4k5f7lvwvf3pk5z030drm0m5xcdz-ca-test (144B)
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Signed narinfos id=1 count=1
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Uploading 1 narinfos
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   1_commit_pending_closure.sql (9.12ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   1_commit_pending_closure.sql (9.05ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   20260628120000_add_object_size_and_stats.sql (9.03ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 goose: successfully migrated database to version: 20260628120000
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   2_object_stats_trigger.sql (9.82ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Completed upload id=1
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   2_object_stats_trigger.sql (9.96ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 goose: up to current file version: 2
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Upload complete. (68ms)
[niks3-go-unit-tests] --- PASS: TestService_AuthMiddleware_MTLSProxyHeader (0.64s)
[niks3-go-unit-tests] === NAME  TestClientCADerivations
[niks3-go-unit-tests]     client_ca_test.go:180: Narinfo contains CA field: StorePath: /build/TestClientCADerivations2368718488/001/store/bk8p4k5f7lvwvf3pk5z030drm0m5xcdz-ca-test
[niks3-go-unit-tests]         URL: nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst
[niks3-go-unit-tests]         Compression: zstd
[niks3-go-unit-tests]         NarHash: sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n
[niks3-go-unit-tests]         NarSize: 144
[niks3-go-unit-tests]         References: 
[niks3-go-unit-tests]         Deriver: /build/TestClientCADerivations2368718488/001/store/n6by1ilv74121zibja8sfc5ina9w2b60-ca-test.drv
[niks3-go-unit-tests]         CA: fixed:r:sha256:04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n
[niks3-go-unit-tests]     client_ca_test.go:185: Checking for realisation files in S3...
[niks3-go-unit-tests]     client_ca_test.go:207: No realisation files uploaded - this is expected for locally built CA derivations
[niks3-go-unit-tests]     client_ca_test.go:208: Locally built CA derivations don't have DrvOutput IDs until they're substituted from a cache
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   1_commit_pending_closure.sql (10.1ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 OK   2_object_stats_trigger.sql (4.26ms)
[niks3-go-unit-tests] 2026/07/18 13:56:29 goose: up to current file version: 2
[niks3-go-unit-tests]     client_ca_test.go:258: nix copy output: warning: you don't have Internet access; disabling some network-dependent features
[niks3-go-unit-tests]         warning: failed to create TLS context for AWS credential providers; SSO, STS WebIdentity, and ECS container authentication will be unavailable
[niks3-go-unit-tests]         error: binary cache 's3://bucket36?endpoint=http://localhost:41767&region=eu-west-1' is for Nix stores with prefix '/nix/store', not '/build/TestClientCADerivations2368718488/001/store'
[niks3-go-unit-tests]     client_ca_test.go:259: nix copy failed (might be expected with isolated stores): exit status 1
[niks3-go-unit-tests] --- PASS: TestClientCADerivations (1.54s)
[niks3-go-unit-tests] 2026/07/18 13:56:29 WARN Request failed, retrying attempt=2 max_attempts=6 backoff=215.844367ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:29 WARN Authentication failed token_preview=invalid-token token_length=13 reason="static API token mismatch"
[niks3-go-unit-tests] --- PASS: TestUploadHandlersRejectOversizedBody (0.13s)
[niks3-go-unit-tests]     --- PASS: TestUploadHandlersRejectOversizedBody/request_more_parts (0.03s)
[niks3-go-unit-tests]     --- PASS: TestUploadHandlersRejectOversizedBody/complete_multipart (0.03s)
[niks3-go-unit-tests]     --- PASS: TestUploadHandlersRejectOversizedBody/create_pending_closure (0.61s)
[niks3-go-unit-tests] 2026/07/18 13:56:29 WARN Request failed, retrying attempt=3 max_attempts=6 backoff=382.214583ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=1 objects-marked-for-deletion=3 objects-deleted-after-grace-period=2193 objects-failed-to-delete=0
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Vacuumed table table=pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Vacuumed table table=pending_objects
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Vacuumed table table=multipart_uploads
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Vacuumed table table=closures
[niks3-go-unit-tests] 2026/07/18 13:56:29 INFO Vacuumed table table=objects
[niks3-go-unit-tests] 2026/07/18 13:56:29 WARN Request failed, retrying attempt=4 max_attempts=6 backoff=804.559149ms error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures
[niks3-go-unit-tests] === NAME  TestOrphanedObjectsGCStressTest
[niks3-go-unit-tests]     orphaned_objects_gc_test.go:425: Created 10 active closures, 5 to-delete closures, 20 orphaned chains
[niks3-go-unit-tests]     orphaned_objects_gc_test.go:446: Marked 210 objects for deletion
[niks3-go-unit-tests]     orphaned_objects_gc_test.go:509: Stress test completed successfully:
[niks3-go-unit-tests]     orphaned_objects_gc_test.go:510:   - Active objects preserved: 20
[niks3-go-unit-tests]     orphaned_objects_gc_test.go:511:   - Objects deleted: 210
[niks3-go-unit-tests]     orphaned_objects_gc_test.go:512:   - Total GC'd: 210
[niks3-go-unit-tests] --- PASS: TestOrphanedObjectsGCStressTest (3.43s)
[niks3-go-unit-tests] 2026/07/18 13:56:30 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2001 objects_failed=0
[niks3-go-unit-tests] === NAME  TestPinProtectsFromGC
[niks3-go-unit-tests]     client_integration_test.go:709: Pin successfully protected closure from garbage collection
[niks3-go-unit-tests] --- PASS: TestPinProtectsFromGC (3.91s)
[niks3-go-unit-tests] 2026/07/18 13:56:30 WARN Request failed, retrying attempt=5 max_attempts=6 backoff=1.57384879s error="Post \"http://localhost:19999/api/pending_closures\": dial tcp [::1]:19999: connect: connection refused" url=http://localhost:19999/api/pending_closures
[niks3-go-unit-tests] 2026/07/18 13:56:31 INFO Garbage collection progress phase="" failed_uploads_deleted=0 old_closures_deleted=1 objects_marked=3 objects_deleted=2193 objects_failed=0
[niks3-go-unit-tests] === NAME  TestClientIntegration
[niks3-go-unit-tests]     client_integration_test.go:303: Objects in database after GC:
[niks3-go-unit-tests]     client_integration_test.go:303: Successfully deleted all objects with GC --force
[niks3-go-unit-tests] --- PASS: TestClientIntegration (3.85s)
[niks3-go-unit-tests] 2026/07/18 13:56:32 WARN Rate limiter enabled after throttle name=s3-test rate=5
[niks3-go-unit-tests] 2026/07/18 13:56:32 WARN S3 rate limit hit operation="complete multipart upload" error="Please reduce your request rate."
[niks3-go-unit-tests] === NAME  TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle
[niks3-go-unit-tests]     throttle_test.go:213: Proxy stats: total=15, throttled=10, completeMultipart=10
[niks3-go-unit-tests]     throttle_test.go:215: Rate limiter: enabled=true, rate=5.00
[niks3-go-unit-tests] --- PASS: TestCompleteMultipartUploadHandler_RateLimitTriggersThrottle (5.76s)
[niks3-go-unit-tests] --- PASS: TestClientErrorHandling (0.00s)
[niks3-go-unit-tests]     --- PASS: TestClientErrorHandling/InvalidStorePath (0.45s)
[niks3-go-unit-tests]     --- PASS: TestClientErrorHandling/InvalidAuthToken (0.62s)
[niks3-go-unit-tests]     --- PASS: TestClientErrorHandling/ServerNotAvailable (3.40s)
[niks3-go-unit-tests] PASS
[niks3-go-unit-tests] 2026-07-18 13:56:32.701 UTC [99] LOG:  received smart shutdown request
[niks3-go-unit-tests] 2026-07-18 13:56:32.712 UTC [99] LOG:  background worker "logical replication launcher" (PID 109) exited with exit code 1
[niks3-go-unit-tests] 2026-07-18 13:56:32.715 UTC [104] LOG:  shutting down
[niks3-go-unit-tests] 2026-07-18 13:56:32.722 UTC [104] LOG:  checkpoint starting: shutdown immediate
[niks3-go-unit-tests] 2026/07/18 13:56:42 ERROR failed to kill rustfs error="no such process"
[niks3-go-unit-tests] 2026/07/18 13:56:42 INFO killed rustfs
[niks3-go-unit-tests] 2026/07/18 13:56:42 ERROR failed to wait for rustfs error="signal: killed"
[niks3-go-unit-tests] 2026-07-18 13:56:42.726 UTC [104] PANIC:  could not fsync file "base/20408/1418": No such file or directory
[niks3-go-unit-tests] Running OIDC tests...
[niks3-go-unit-tests] === RUN   TestGlobMatch
[niks3-go-unit-tests] === PAUSE TestGlobMatch
[niks3-go-unit-tests] === RUN   TestAudienceForIssuer
[niks3-go-unit-tests] === PAUSE TestAudienceForIssuer
[niks3-go-unit-tests] === RUN   TestValidateToken_ValidToken
[niks3-go-unit-tests] === PAUSE TestValidateToken_ValidToken
[niks3-go-unit-tests] === RUN   TestValidateToken_WrongAudience
[niks3-go-unit-tests] === PAUSE TestValidateToken_WrongAudience
[niks3-go-unit-tests] === RUN   TestValidateToken_Expired
[niks3-go-unit-tests] === PAUSE TestValidateToken_Expired
[niks3-go-unit-tests] === RUN   TestValidateToken_BoundClaimsMismatch
[niks3-go-unit-tests] === PAUSE TestValidateToken_BoundClaimsMismatch
[niks3-go-unit-tests] === RUN   TestValidateToken_BoundSubjectMismatch
[niks3-go-unit-tests] === PAUSE TestValidateToken_BoundSubjectMismatch
[niks3-go-unit-tests] === RUN   TestValidateToken_MultipleProviders
[niks3-go-unit-tests] === PAUSE TestValidateToken_MultipleProviders
[niks3-go-unit-tests] === RUN   TestValidateToken_NoMatchingProvider
[niks3-go-unit-tests] === PAUSE TestValidateToken_NoMatchingProvider
[niks3-go-unit-tests] === CONT  TestGlobMatch
[niks3-go-unit-tests] === CONT  TestValidateToken_BoundClaimsMismatch
[niks3-go-unit-tests] === CONT  TestValidateToken_BoundSubjectMismatch
[niks3-go-unit-tests] === RUN   TestGlobMatch/foo_foo
[niks3-go-unit-tests] === PAUSE TestGlobMatch/foo_foo
[niks3-go-unit-tests] === RUN   TestGlobMatch/foo_bar
[niks3-go-unit-tests] === PAUSE TestGlobMatch/foo_bar
[niks3-go-unit-tests] === RUN   TestGlobMatch/*_
[niks3-go-unit-tests] === PAUSE TestGlobMatch/*_
[niks3-go-unit-tests] === RUN   TestGlobMatch/*_anything
[niks3-go-unit-tests] === PAUSE TestGlobMatch/*_anything
[niks3-go-unit-tests] === CONT  TestValidateToken_Expired
[niks3-go-unit-tests] === CONT  TestValidateToken_WrongAudience
[niks3-go-unit-tests] === CONT  TestValidateToken_ValidToken
[niks3-go-unit-tests] === CONT  TestAudienceForIssuer
[niks3-go-unit-tests] --- PASS: TestAudienceForIssuer (0.00s)
[niks3-go-unit-tests] === CONT  TestValidateToken_MultipleProviders
[niks3-go-unit-tests] === CONT  TestValidateToken_NoMatchingProvider
[niks3-go-unit-tests] === RUN   TestGlobMatch/foo*_foo
[niks3-go-unit-tests] === PAUSE TestGlobMatch/foo*_foo
[niks3-go-unit-tests] === RUN   TestGlobMatch/foo*_foobar
[niks3-go-unit-tests] === PAUSE TestGlobMatch/foo*_foobar
[niks3-go-unit-tests] === RUN   TestGlobMatch/foo*_bar
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO OIDC provider initialized name=test
[niks3-go-unit-tests] === PAUSE TestGlobMatch/foo*_bar
[niks3-go-unit-tests] === RUN   TestGlobMatch/*bar_bar
[niks3-go-unit-tests] === PAUSE TestGlobMatch/*bar_bar
[niks3-go-unit-tests] === RUN   TestGlobMatch/*bar_foobar
[niks3-go-unit-tests] === PAUSE TestGlobMatch/*bar_foobar
[niks3-go-unit-tests] === RUN   TestGlobMatch/*bar_foo
[niks3-go-unit-tests] === PAUSE TestGlobMatch/*bar_foo
[niks3-go-unit-tests] === RUN   TestGlobMatch/foo*bar_foobar
[niks3-go-unit-tests] === PAUSE TestGlobMatch/foo*bar_foobar
[niks3-go-unit-tests] === RUN   TestGlobMatch/foo*bar_foo123bar
[niks3-go-unit-tests] === PAUSE TestGlobMatch/foo*bar_foo123bar
[niks3-go-unit-tests] === RUN   TestGlobMatch/foo*bar_foobarbaz
[niks3-go-unit-tests] === PAUSE TestGlobMatch/foo*bar_foobarbaz
[niks3-go-unit-tests] === RUN   TestGlobMatch/*/*_foo/bar
[niks3-go-unit-tests] === PAUSE TestGlobMatch/*/*_foo/bar
[niks3-go-unit-tests] === RUN   TestGlobMatch/*/*_foo
[niks3-go-unit-tests] === PAUSE TestGlobMatch/*/*_foo
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO OIDC provider initialized name=test
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO OIDC provider initialized name=test
[niks3-go-unit-tests] === RUN   TestGlobMatch/refs/heads/*_refs/heads/main
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO OIDC provider initialized name=test
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO OIDC provider initialized name=test
[niks3-go-unit-tests] === PAUSE TestGlobMatch/refs/heads/*_refs/heads/main
[niks3-go-unit-tests] === RUN   TestGlobMatch/refs/heads/*_refs/tags/v1.0
[niks3-go-unit-tests] === PAUSE TestGlobMatch/refs/heads/*_refs/tags/v1.0
[niks3-go-unit-tests] === RUN   TestGlobMatch/refs/*/main_refs/heads/main
[niks3-go-unit-tests] === PAUSE TestGlobMatch/refs/*/main_refs/heads/main
[niks3-go-unit-tests] === RUN   TestGlobMatch/fo?_foo
[niks3-go-unit-tests] === PAUSE TestGlobMatch/fo?_foo
[niks3-go-unit-tests] === RUN   TestGlobMatch/fo?_fo
[niks3-go-unit-tests] === PAUSE TestGlobMatch/fo?_fo
[niks3-go-unit-tests] === RUN   TestGlobMatch/fo?_fooo
[niks3-go-unit-tests] === PAUSE TestGlobMatch/fo?_fooo
[niks3-go-unit-tests] === RUN   TestGlobMatch/?oo_foo
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO OIDC provider initialized name=provider1
[niks3-go-unit-tests] === PAUSE TestGlobMatch/?oo_foo
[niks3-go-unit-tests] === RUN   TestGlobMatch/?oo_boo
[niks3-go-unit-tests] === PAUSE TestGlobMatch/?oo_boo
[niks3-go-unit-tests] === RUN   TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main
[niks3-go-unit-tests] === PAUSE TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main
[niks3-go-unit-tests] === RUN   TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main
[niks3-go-unit-tests] === PAUSE TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main
[niks3-go-unit-tests] === CONT  TestGlobMatch/foo_foo
[niks3-go-unit-tests] === CONT  TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main
[niks3-go-unit-tests] === CONT  TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main
[niks3-go-unit-tests] === CONT  TestGlobMatch/foo*bar_foobarbaz
[niks3-go-unit-tests] === CONT  TestGlobMatch/foo*_foobar
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO OIDC provider initialized name=provider1
[niks3-go-unit-tests] === CONT  TestGlobMatch/foo*_foo
[niks3-go-unit-tests] === CONT  TestGlobMatch/*_anything
[niks3-go-unit-tests] === CONT  TestGlobMatch/*_
[niks3-go-unit-tests] === CONT  TestGlobMatch/foo_bar
[niks3-go-unit-tests] === CONT  TestGlobMatch/foo*bar_foo123bar
[niks3-go-unit-tests] === CONT  TestGlobMatch/refs/*/main_refs/heads/main
[niks3-go-unit-tests] === CONT  TestGlobMatch/?oo_boo
[niks3-go-unit-tests] === CONT  TestGlobMatch/fo?_fooo
[niks3-go-unit-tests] === CONT  TestGlobMatch/*bar_foo
[niks3-go-unit-tests] === CONT  TestGlobMatch/fo?_fo
[niks3-go-unit-tests] === CONT  TestGlobMatch/refs/heads/*_refs/tags/v1.0
[niks3-go-unit-tests] === CONT  TestGlobMatch/*bar_foobar
[niks3-go-unit-tests] === CONT  TestGlobMatch/fo?_foo
[niks3-go-unit-tests] === CONT  TestGlobMatch/*/*_foo
[niks3-go-unit-tests] === CONT  TestGlobMatch/*bar_bar
[niks3-go-unit-tests] === CONT  TestGlobMatch/foo*_bar
[niks3-go-unit-tests] === CONT  TestGlobMatch/foo*bar_foobar
[niks3-go-unit-tests] === CONT  TestGlobMatch/?oo_foo
[niks3-go-unit-tests] === CONT  TestGlobMatch/*/*_foo/bar
[niks3-go-unit-tests] === CONT  TestGlobMatch/refs/heads/*_refs/heads/main
[niks3-go-unit-tests] --- PASS: TestGlobMatch (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/foo_foo (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:otherorg/myrepo:ref:refs/heads/main (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/repo:myorg/*:*_repo:myorg/myrepo:ref:refs/heads/main (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/foo*bar_foobarbaz (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/foo*_foobar (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/foo*_foo (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/*_anything (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/*_ (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/foo_bar (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/foo*bar_foo123bar (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/refs/*/main_refs/heads/main (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/?oo_boo (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/fo?_fooo (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/*bar_foo (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/fo?_fo (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/refs/heads/*_refs/tags/v1.0 (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/*bar_foobar (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/fo?_foo (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/*/*_foo (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/*bar_bar (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/foo*_bar (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/foo*bar_foobar (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/?oo_foo (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/*/*_foo/bar (0.00s)
[niks3-go-unit-tests]     --- PASS: TestGlobMatch/refs/heads/*_refs/heads/main (0.00s)
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO OIDC provider initialized name=provider2
[niks3-go-unit-tests] --- PASS: TestValidateToken_WrongAudience (0.00s)
[niks3-go-unit-tests] --- PASS: TestValidateToken_ValidToken (0.00s)
[niks3-go-unit-tests] --- PASS: TestValidateToken_Expired (0.00s)
[niks3-go-unit-tests] --- PASS: TestValidateToken_BoundClaimsMismatch (0.00s)
[niks3-go-unit-tests] --- PASS: TestValidateToken_BoundSubjectMismatch (0.00s)
[niks3-go-unit-tests] --- PASS: TestValidateToken_NoMatchingProvider (0.01s)
[niks3-go-unit-tests] --- PASS: TestValidateToken_MultipleProviders (0.01s)
[niks3-go-unit-tests] PASS
[niks3-go-unit-tests] Running hook tests...
[niks3-go-unit-tests] === RUN   TestSendPathsEmpty
[niks3-go-unit-tests] === PAUSE TestSendPathsEmpty
[niks3-go-unit-tests] === RUN   TestQueueEnqueueAndFetch
[niks3-go-unit-tests] === PAUSE TestQueueEnqueueAndFetch
[niks3-go-unit-tests] === RUN   TestQueueDeduplication
[niks3-go-unit-tests] === PAUSE TestQueueDeduplication
[niks3-go-unit-tests] === RUN   TestQueueRemove
[niks3-go-unit-tests] === PAUSE TestQueueRemove
[niks3-go-unit-tests] === RUN   TestQueueFetchBatchLimit
[niks3-go-unit-tests] === PAUSE TestQueueFetchBatchLimit
[niks3-go-unit-tests] === RUN   TestQueueFetchRemoveLifecycle
[niks3-go-unit-tests] === PAUSE TestQueueFetchRemoveLifecycle
[niks3-go-unit-tests] === RUN   TestQueueConcurrentWriters
[niks3-go-unit-tests] === PAUSE TestQueueConcurrentWriters
[niks3-go-unit-tests] === RUN   TestServerClientIntegration
[niks3-go-unit-tests] === PAUSE TestServerClientIntegration
[niks3-go-unit-tests] === RUN   TestServerQueueError
[niks3-go-unit-tests] === PAUSE TestServerQueueError
[niks3-go-unit-tests] === RUN   TestGetListenerSocketActivation
[niks3-go-unit-tests]     server_test.go:210: === RUN   TestGetListenerSocketActivation
[niks3-go-unit-tests]         --- PASS: TestGetListenerSocketActivation (0.00s)
[niks3-go-unit-tests]         PASS
[niks3-go-unit-tests]         
[niks3-go-unit-tests] --- PASS: TestGetListenerSocketActivation (0.12s)
[niks3-go-unit-tests] === RUN   TestWorkerUploadsAndRemoves
[niks3-go-unit-tests] === PAUSE TestWorkerUploadsAndRemoves
[niks3-go-unit-tests] === RUN   TestWorkerSkipsGCdPaths
[niks3-go-unit-tests] === PAUSE TestWorkerSkipsGCdPaths
[niks3-go-unit-tests] === RUN   TestWorkerPrunesClosureDeps
[niks3-go-unit-tests] === PAUSE TestWorkerPrunesClosureDeps
[niks3-go-unit-tests] === CONT  TestSendPathsEmpty
[niks3-go-unit-tests] --- PASS: TestSendPathsEmpty (0.00s)
[niks3-go-unit-tests] === CONT  TestQueueFetchRemoveLifecycle
[niks3-go-unit-tests] === CONT  TestQueueConcurrentWriters
[niks3-go-unit-tests] === CONT  TestWorkerUploadsAndRemoves
[niks3-go-unit-tests] === CONT  TestQueueRemove
[niks3-go-unit-tests] === CONT  TestServerQueueError
[niks3-go-unit-tests] === CONT  TestWorkerPrunesClosureDeps
[niks3-go-unit-tests] === CONT  TestQueueEnqueueAndFetch
[niks3-go-unit-tests] === CONT  TestQueueFetchBatchLimit
[niks3-go-unit-tests] === CONT  TestQueueDeduplication
[niks3-go-unit-tests] === CONT  TestWorkerSkipsGCdPaths
[niks3-go-unit-tests] === CONT  TestServerClientIntegration
[niks3-go-unit-tests] 2026/07/18 13:56:43 ERROR Failed to queue paths error="permission denied" count=1
[niks3-go-unit-tests] --- PASS: TestServerQueueError (0.00s)
[niks3-go-unit-tests] --- PASS: TestServerClientIntegration (0.00s)
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO Upload queue status pending=2
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO Uploading batch count=2
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO Upload queue status pending=2
[niks3-go-unit-tests] 2026/07/18 13:56:43 WARN Store path no longer exists (garbage collected?), removing from queue path=/build/TestWorkerSkipsGCdPaths4163522898/002/nonexistent
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO Upload queue status pending=2
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO Uploading batch count=1
[niks3-go-unit-tests] 2026/07/18 13:56:43 INFO Uploading batch count=1
[niks3-go-unit-tests] --- PASS: TestQueueEnqueueAndFetch (0.20s)
[niks3-go-unit-tests] --- PASS: TestQueueFetchBatchLimit (0.21s)
[niks3-go-unit-tests] --- PASS: TestQueueDeduplication (0.21s)
[niks3-go-unit-tests] --- PASS: TestQueueFetchRemoveLifecycle (0.23s)
[niks3-go-unit-tests] --- PASS: TestQueueRemove (0.23s)
[niks3-go-unit-tests] --- PASS: TestWorkerUploadsAndRemoves (0.24s)
[niks3-go-unit-tests] --- PASS: TestWorkerPrunesClosureDeps (0.24s)
[niks3-go-unit-tests] --- PASS: TestWorkerSkipsGCdPaths (0.28s)
[niks3-go-unit-tests] --- PASS: TestQueueConcurrentWriters (4.16s)
[niks3-go-unit-tests] PASS
[niks3-go-unit-tests:post-build] Uploading to the NixCI staging cache: /nix/store/wvf1cvf0c9a0gcjh1cvslwm5cdjcm3bp-niks3-go-unit-tests
[niks3-go-unit-tests:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
[niks3-go-unit-tests:post-build] copying 1 paths...
[niks3-go-unit-tests:post-build] copying path '/nix/store/wvf1cvf0c9a0gcjh1cvslwm5cdjcm3bp-niks3-go-unit-tests' to 'https://cache.staging.nix-ci.com'...
[niks3-go-unit-tests:post-build] warning: 'warn-short-path-literals' is deprecated, use 'lint-short-path-literals = ignore' instead
[niks3-go-unit-tests:post-build] copying 1 paths...
[niks3-go-unit-tests:post-build] copying path '/nix/store/pjvglmgx4k8v1fgng4daqcmi9q9hf5f5-niks3-go-unit-tests.drv' to 'https://cache.staging.nix-ci.com'...
Uploaded niks3-go-unit-tests in 581ms
Progress: 1 of 2 built, 48 of 48 downloaded from cache
Built niks3-go-unit-tests in 26.2s
Progress: 2 of 2 built, 48 of 48 downloaded from cache
/nix/store/wvf1cvf0c9a0gcjh1cvslwm5cdjcm3bp-niks3-go-unit-tests
Build succeeded.