"go test -v -timeout 1h0m0s -remote TestShade: -verbose" - Starting (try 1/5)
=== RUN   TestWriteChunkError
--- PASS: TestWriteChunkError (0.01s)
=== RUN   TestIntegration
2026/09/18 02:27:21 DEBUG : Using config file from "/home/rclone/.rclone.conf"
    fstests.go:464: Using remote "TestShade:"
2026/09/18 02:27:21 DEBUG : Creating backend with remote "TestShade:rclone-test-xuzupuf0zebe"
2026/09/18 02:27:21 DEBUG : Creating new ShadeFS backend with drive: cf193d00-c783-4bab-aa7a-fd84b3700d27
=== RUN   TestIntegration/FsCheckWrap
    fstests.go:505: Not a wrapping Fs
=== RUN   TestIntegration/FsCommand
    fstests.go:533: No commands in this remote
=== RUN   TestIntegration/FsRmdirNotFound
2026/09/18 02:27:22 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:22 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:27:22 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:22 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:27:22 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:22 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:27:22 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:22 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:27:22 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:22 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:27:22 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:22 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:27:23 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:23 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:27:23 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:23 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:27:25 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:25 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:27:27 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsString
=== RUN   TestIntegration/FsName
=== RUN   TestIntegration/FsRoot
=== RUN   TestIntegration/FsRmdirEmpty
2026/09/18 02:27:32 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:27:35 DEBUG : pacer: Reducing sleep to 640ms
=== RUN   TestIntegration/FsMkdir
2026/09/18 02:27:36 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:27:37 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:37 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:27:37 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:37 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:27:38 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:38 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:27:39 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:39 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:27:42 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsMkdirRmdirSubdir
2026/09/18 02:27:42 INFO  : dir/subdir: Making directory
2026/09/18 02:27:47 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:27:49 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:27:51 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:27:51 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:27:52 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:52 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:27:52 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:52 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:27:52 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:52 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:27:53 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:53 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:27:54 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:27:54 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:27:57 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:27:57 INFO  : dir/subdir: Removing directory
2026/09/18 02:28:02 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:28:04 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:28:06 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:28:06 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:06 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:28:07 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:07 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:28:07 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:28:07 INFO  : dir: Removing directory
2026/09/18 02:28:08 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:28:09 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:28:09 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:09 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:28:10 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:10 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:28:10 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:10 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:28:11 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:11 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:28:12 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:12 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:28:14 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsListEmpty
2026/09/18 02:28:19 DEBUG : pacer: Reducing sleep to 1.28s
=== RUN   TestIntegration/FsMkdir/FsListDirEmpty
2026/09/18 02:28:22 DEBUG : pacer: Reducing sleep to 640ms
=== RUN   TestIntegration/FsMkdir/FsListRDirEmpty
    fstests.go:425: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsListDirNotFound
2026/09/18 02:28:23 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:28:23 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-xuzupuf0zebe: Error from List call: HTTP error 404 (404 Not Found) returned body: "ENTRY_NOT_FOUND"
2026/09/18 02:28:23 ERROR : does not exist: error listing: directory not found
=== RUN   TestIntegration/FsMkdir/FsListRDirNotFound
    fstests.go:425: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsEncoding
2026/09/18 02:28:24 DEBUG : pacer: Reducing sleep to 160ms
=== RUN   TestIntegration/FsMkdir/FsEncoding/control_chars
    fstests.go:734: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡"
2026/09/18 02:28:24 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:28:25 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:28:25 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:28:25 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:28:25 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:28:27 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:27 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:28:27 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:27 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:28:27 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:27 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:28:27 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:27 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:28:28 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:28 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:28:28 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:28 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:28:28 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:28 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:28:29 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:29 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:28:30 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:30 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:28:33 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:28:38 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:28:40 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:28:42 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:42 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:28:42 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:42 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:28:43 DEBUG : pacer: Reducing sleep to 1.28s
=== RUN   TestIntegration/FsMkdir/FsEncoding/dot
    fstests.go:734: testing "．"
2026/09/18 02:28:46 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:28:48 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:28:48 DEBUG : ．/．: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:28:48 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:28:48 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:28:50 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:28:50 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:28:50 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:28:50 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:50 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:28:50 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:50 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:28:50 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:50 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:28:51 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:51 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:28:51 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:51 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:28:51 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:51 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:28:51 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:51 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:28:52 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:52 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:28:53 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:28:53 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:28:56 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:29:01 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:29:03 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:29:05 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:05 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:29:05 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:05 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:29:07 DEBUG : pacer: Reducing sleep to 1.28s
=== RUN   TestIntegration/FsMkdir/FsEncoding/dot_dot
    fstests.go:734: testing "．．"
2026/09/18 02:29:09 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:29:11 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:29:11 DEBUG : ．．/．．: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:29:11 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:29:12 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:29:13 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:29:13 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:29:13 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:29:13 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:29:13 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:13 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:29:13 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:13 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:29:14 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:14 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:29:14 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:14 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:29:14 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:14 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:29:14 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:29:15 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:15 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:29:15 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:15 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:29:16 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:16 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:29:17 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:17 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:29:20 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:29:25 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:29:27 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:27 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:29:29 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:29 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:29:31 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsEncoding/punctuation
    fstests.go:734: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"
2026/09/18 02:29:36 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:29:39 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:29:39 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:29:40 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:29:41 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:29:42 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:29:42 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:29:42 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:29:43 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:29:43 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:43 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:29:43 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:43 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:29:43 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:43 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:29:43 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:43 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:29:43 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:43 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:29:44 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:44 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:29:44 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:44 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:29:45 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:45 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:29:46 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:46 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:29:48 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:29:53 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:29:56 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:29:57 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:57 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:29:58 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:58 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:29:59 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:29:59 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:30:02 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_space
    fstests.go:734: testing " leading space"
2026/09/18 02:30:07 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:30:10 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:30:10 DEBUG :  leading space/ leading space: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:30:11 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:30:12 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:30:13 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:30:13 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:30:13 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:30:13 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:30:13 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:30:14 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:14 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:30:14 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:14 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:30:14 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:14 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:30:14 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:14 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:30:14 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:14 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:30:14 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:14 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:30:15 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:15 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:30:16 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:16 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:30:19 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:30:24 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:30:27 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:30:28 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:28 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:30:29 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:29 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:30:30 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:30 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:30:32 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_tilde
    fstests.go:734: testing "~leading tilde"
2026/09/18 02:30:38 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:30:40 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:30:40 DEBUG : ~leading tilde/~leading tilde: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:30:41 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:30:42 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:30:43 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:30:44 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:30:44 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:30:44 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:30:44 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:44 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:30:44 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:44 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:30:44 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:44 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:30:44 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:44 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:30:45 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:45 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:30:45 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:45 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:30:45 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:45 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:30:46 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:46 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:30:47 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:47 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:30:50 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:30:55 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:30:57 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:30:59 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:59 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:30:59 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:30:59 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:31:00 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:00 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:31:03 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_CR
    fstests.go:734: testing "␍leading CR"
2026/09/18 02:31:08 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:31:11 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:31:11 DEBUG : ␍leading CR/␍leading CR: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:31:12 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:31:13 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:31:14 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:31:14 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:31:14 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:31:15 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:31:15 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:15 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:31:15 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:15 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:31:15 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:15 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:31:15 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:15 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:31:15 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:15 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:31:15 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:15 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:31:16 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:16 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:31:16 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:16 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:31:18 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:18 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:31:20 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:31:25 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:31:28 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:31:29 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:29 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:31:30 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:30 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:31:31 DEBUG : pacer: Reducing sleep to 1.28s
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_LF
    fstests.go:734: testing "␊leading LF"
2026/09/18 02:31:34 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:31:35 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:31:35 DEBUG : ␊leading LF/␊leading LF: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:31:36 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:31:36 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:31:37 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:31:37 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:31:38 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:31:38 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:38 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:31:38 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:31:38 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:38 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:31:38 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:38 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:31:38 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:38 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:31:39 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:39 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:31:39 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:39 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:31:39 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:39 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:31:39 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:39 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:31:40 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:40 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:31:41 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:41 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:31:44 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:31:49 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:31:51 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:51 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:31:53 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:31:53 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:31:55 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_HT
    fstests.go:734: testing "␉leading HT"
2026/09/18 02:32:00 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:32:03 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:32:03 DEBUG : ␉leading HT/␉leading HT: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:32:04 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:32:05 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:32:06 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:32:06 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:32:06 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:32:07 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:32:07 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:32:07 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:07 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:32:07 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:07 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:32:07 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:07 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:32:07 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:07 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:32:07 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:07 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:32:08 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:08 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:32:08 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:08 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:32:10 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:10 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:32:12 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:32:17 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:32:20 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:32:21 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:21 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:32:22 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:22 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:32:23 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:23 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:32:26 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_VT
    fstests.go:734: testing "␋leading VT"
2026/09/18 02:32:31 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:32:34 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:32:34 DEBUG : ␋leading VT/␋leading VT: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:32:35 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:32:36 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:32:37 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:32:37 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:32:37 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:32:37 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:32:37 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:32:37 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:37 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:32:38 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:38 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:32:38 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:38 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:32:38 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:38 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:32:38 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:38 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:32:38 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:38 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:32:39 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:39 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:32:40 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:32:43 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:43 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:32:44 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:44 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:32:47 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:32:52 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:32:54 DEBUG : pacer: Reducing sleep to 640ms
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_dot
    fstests.go:734: testing ".leading dot"
2026/09/18 02:32:56 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:32:56 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:32:56 DEBUG : .leading dot/.leading dot: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:32:57 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:32:57 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:32:58 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:32:58 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:32:59 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:59 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:32:59 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:59 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:32:59 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:59 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:32:59 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:59 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:32:59 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:59 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:32:59 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:32:59 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:33:00 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:00 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:33:00 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:00 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:33:02 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:33:04 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:04 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:33:06 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:06 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:33:08 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:33:13 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:33:16 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:16 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:33:17 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:17 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:33:20 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_space
    fstests.go:734: testing "trailing space "
2026/09/18 02:33:25 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:33:28 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:33:28 DEBUG : trailing space /trailing space : multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:33:29 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:33:29 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:33:30 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:33:31 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:33:31 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:33:31 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:33:31 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:31 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:33:31 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:31 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:33:31 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:31 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:33:32 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:32 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:33:32 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:32 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:33:32 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:32 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:33:32 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:32 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:33:33 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:33 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:33:34 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:34 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:33:37 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:33:42 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:33:44 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:33:46 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:46 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:33:46 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:46 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:33:48 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:33:48 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:33:50 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_CR
    fstests.go:734: testing "trailing CR␍"
2026/09/18 02:33:55 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:33:58 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:33:58 DEBUG : trailing CR␍/trailing CR␍: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:33:59 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:34:00 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:34:01 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:34:01 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:34:01 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:34:01 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:34:02 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:02 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:34:02 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:02 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:34:02 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:02 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:34:02 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:02 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:34:02 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:02 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:34:02 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:02 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:34:03 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:03 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:34:03 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:34:05 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:05 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:34:05 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:05 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:34:07 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:07 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:34:09 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:34:14 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:34:17 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:17 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:34:18 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:18 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:34:21 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_LF
    fstests.go:734: testing "trailing LF␊"
2026/09/18 02:34:26 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:34:29 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:34:29 DEBUG : trailing LF␊/trailing LF␊: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:34:30 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:34:30 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:34:32 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:34:32 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:34:32 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:34:32 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:34:32 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:34:32 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:32 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:34:33 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:33 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:34:33 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:33 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:34:33 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:33 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:34:33 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:33 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:34:33 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:33 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:34:34 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:34 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:34:35 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:35 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:34:38 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:34:43 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:34:46 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:34:47 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:47 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:34:47 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:47 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:34:49 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:34:49 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:34:51 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_HT
    fstests.go:734: testing "trailing HT␉"
2026/09/18 02:34:56 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:34:59 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:34:59 DEBUG : trailing HT␉/trailing HT␉: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:35:00 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:35:01 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:35:02 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:35:02 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:35:02 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:35:03 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:35:03 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:35:03 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:03 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:35:03 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:03 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:35:03 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:03 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:35:03 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:03 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:35:04 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:04 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:35:04 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:04 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:35:05 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:05 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:35:06 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:06 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:35:08 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:35:13 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:35:16 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:35:17 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:17 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:35:18 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:18 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:35:19 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:19 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:35:22 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_VT
    fstests.go:734: testing "trailing VT␋"
2026/09/18 02:35:27 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:35:30 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:35:30 DEBUG : trailing VT␋/trailing VT␋: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:35:31 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:35:32 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:35:33 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:35:33 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:35:33 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:35:33 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:35:33 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:35:34 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:34 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:35:34 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:34 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:35:34 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:34 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:35:34 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:34 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:35:34 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:34 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:35:35 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:35 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:35:35 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:35:36 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:36 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:35:37 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:37 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:35:38 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:38 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:35:41 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:35:46 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:35:49 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:49 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:35:50 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:35:50 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:35:53 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_dot
    fstests.go:734: testing "trailing dot."
2026/09/18 02:35:58 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:36:00 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:36:00 DEBUG : trailing dot./trailing dot.: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:36:01 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:36:02 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:36:04 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:36:04 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:36:04 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:36:04 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:36:04 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:04 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:36:04 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:04 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:36:05 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:05 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:36:05 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:05 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:36:05 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:05 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:36:05 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:36:05 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:05 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:36:05 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:05 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:36:06 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:06 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:36:06 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:06 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:36:08 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:08 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:36:10 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:36:15 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:36:18 DEBUG : pacer: Reducing sleep to 640ms
=== RUN   TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8
    fstests.go:725: Skipping invalid UTF-8
=== RUN   TestIntegration/FsMkdir/FsEncoding/URL_encoding
    fstests.go:734: testing "test%46.txt"
2026/09/18 02:36:19 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:36:20 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:36:20 DEBUG : test%46.txt/test%46.txt: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:36:20 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:36:21 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:36:22 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:36:22 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:36:23 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:23 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:36:23 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:23 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:36:23 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:23 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:36:23 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:23 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:36:23 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:23 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:36:23 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:23 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:36:24 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:24 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:36:24 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:36:26 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:26 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:36:26 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:26 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:36:27 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:27 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:36:30 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:36:35 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:36:38 DEBUG : pacer: Reducing sleep to 640ms
=== RUN   TestIntegration/FsMkdir/FsNewObjectNotFound
2026/09/18 02:36:39 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:36:40 DEBUG : pacer: Reducing sleep to 160ms
=== RUN   TestIntegration/FsMkdir/FsPutError
2026/09/18 02:36:40 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:36:40 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:36:40 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:36:40 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:36:41 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Cancelling multipart upload
=== RUN   TestIntegration/FsMkdir/FsPutZeroLength
2026/09/18 02:36:42 DEBUG : zero-length-file: multipart upload: starting chunk 0 size 0 offset 0/0
2026/09/18 02:36:43 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:43 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:36:43 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:43 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:36:43 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:43 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:36:43 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:43 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:36:43 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:43 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:36:43 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:43 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:36:44 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:44 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:36:44 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:44 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:36:46 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:36:46 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:36:48 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsOpenWriterAt
    fstests.go:813: FS has no OpenWriterAt interface
=== RUN   TestIntegration/FsMkdir/FsOpenChunkWriter
2026/09/18 02:36:53 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:36:56 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:36:57 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:36:58 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:36:58 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:36:59 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:36:59 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:36:59 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:37:02 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:02 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:37:02 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:02 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:37:02 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:02 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:37:02 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:02 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:37:02 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:02 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:37:02 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:02 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:37:03 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:03 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:37:03 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:03 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:37:05 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:05 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:37:07 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:37:12 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:37:15 DEBUG : pacer: Reducing sleep to 640ms
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
    fstests.go:899: FS has no ChangeNotify interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles
2026/09/18 02:37:16 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:37:16 DEBUG : file name.txt: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:37:17 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:37:17 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:37:18 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:37:19 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:37:19 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:37:19 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:37:21 DEBUG : file name.txt: multipart upload: starting chunk 0 size 100 offset 0/100
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2
    fstests.go:425: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListR
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot
2026/09/18 02:37:25 DEBUG : Creating backend with remote "TestShade:"
2026/09/18 02:37:25 DEBUG : Config file has changed externally - reloading
2026/09/18 02:37:25 DEBUG : Creating new ShadeFS backend with drive: cf193d00-c783-4bab-aa7a-fd84b3700d27
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot
    fstests.go:425: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListSubdir
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01
    fstests.go:425: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListLevel2
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2
    fstests.go:425: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListFile1
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsNewObject
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive
    fstests.go:1152: Not Case Insensitive
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPurge
    fstests.go:1184: FS has no Purge interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot
    fstests.go:1221: FS has no Purge interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir
2026/09/18 02:37:28 DEBUG : Creating backend with remote "TestShade:rclone-test-xuzupuf0zebe/hello? sausage/êé"
2026/09/18 02:37:28 DEBUG : Creating new ShadeFS backend with drive: cf193d00-c783-4bab-aa7a-fd84b3700d27
2026/09/18 02:37:30 DEBUG : a/b/c/d/e.txt: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:37:32 DEBUG : Waiting for deletions to finish
2026/09/18 02:37:33 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:37:33 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:33 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:37:33 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:33 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:37:33 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:33 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:37:34 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:34 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:37:34 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:34 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:37:34 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:34 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:37:35 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:35 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:37:36 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:36 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:37:39 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:37:39 INFO  : a/b/c/d/e.txt: Deleted
2026/09/18 02:37:44 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:37:46 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:37:47 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:37:48 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:37:48 DEBUG : removing 1 level 4 directories
2026/09/18 02:37:48 INFO  : a/b/c/d: Removing directory
2026/09/18 02:37:48 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:37:49 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:37:49 DEBUG : removing 1 level 3 directories
2026/09/18 02:37:49 INFO  : a/b/c: Removing directory
2026/09/18 02:37:49 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:49 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:37:49 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:49 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:37:49 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:49 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:37:49 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:49 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:37:50 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:50 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:37:50 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:50 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:37:51 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:37:51 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:37:54 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:37:59 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:37:59 DEBUG : removing 1 level 2 directories
2026/09/18 02:37:59 INFO  : a/b: Removing directory
2026/09/18 02:38:02 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:02 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:38:03 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:03 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:38:06 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:38:11 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:38:11 DEBUG : removing 1 level 1 directories
2026/09/18 02:38:11 INFO  : a: Removing directory
2026/09/18 02:38:13 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:38:15 DEBUG : pacer: Reducing sleep to 320ms
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsCopy
    fstests.go:1285: FS has no Copier interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsMove
2026/09/18 02:38:16 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:16 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:38:16 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:16 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:38:16 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:16 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:38:16 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:16 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:38:16 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:16 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:38:16 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:16 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:38:17 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:17 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:38:17 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:17 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:38:19 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:38:21 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:38:22 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:38:23 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:38:23 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:38:24 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:38:24 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:38:24 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:38:25 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:38:25 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:38:25 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:38:25 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:38:25 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:38:25 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:38:25 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:38:25 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:38:25 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:38:26 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:26 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:38:26 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:26 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:38:26 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:26 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:38:26 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:26 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:38:26 DEBUG : pacer: low level retry 10/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:26 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:38:26 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-xuzupuf0zebe: Error from List call: HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND"
2026/09/18 02:38:26 ERROR : moveTest: error listing: directory not found
2026/09/18 02:38:26 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:38:27 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:38:27 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:38:28 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:28 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:38:28 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:38:28 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:28 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:38:28 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:38:28 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:28 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:38:28 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:38:28 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:28 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:38:28 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:38:29 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:38:29 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:38:29 DEBUG : pacer: Reducing sleep to 10ms
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata
    fstests.go:1437: Skipping test as can't write metadata
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsDirMove
2026/09/18 02:38:29 DEBUG : Creating backend with remote "TestShade:rclone-test-yenoriw3rihe"
2026/09/18 02:38:29 DEBUG : Creating new ShadeFS backend with drive: cf193d00-c783-4bab-aa7a-fd84b3700d27
2026/09/18 02:38:30 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-xuzupuf0zebe: Error from List call: HTTP error 404 (404 Not Found) returned body: "ENTRY_NOT_FOUND"
2026/09/18 02:38:32 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:38:32 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:32 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:38:33 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:33 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:38:33 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:33 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:38:33 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:33 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:38:33 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:33 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:38:33 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:33 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:38:34 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:38:35 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:38:36 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:38:36 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-yenoriw3rihe: Purge dir "new_name"
2026/09/18 02:38:36 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:38:36 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:38:36 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-yenoriw3rihe: Purge dir ""
2026/09/18 02:38:37 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:37 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:38:37 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:37 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:38:37 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:37 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:38:37 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:37 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:38:37 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:37 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:38:38 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:38 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:38:39 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:38:39 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:38:42 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:38:47 DEBUG : pacer: Reducing sleep to 1.28s
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPrecision
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectString
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectFs
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectRemote
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectHashes
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectModTime
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType
    fstests.go:1633: MimeType method not supported
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata
    fstests.go:1664: Metadata method not supported
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata
    fstests.go:1735: SetMetadata method not supported
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime
    fstests.go:1792: can't set modified time
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectSize
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectOpen
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate
2026/09/18 02:38:54 DEBUG : file name.txt: multipart upload: starting chunk 0 size 200 offset 0/200
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectStorable
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsIsFile
2026/09/18 02:38:56 DEBUG : Creating backend with remote "TestShade:rclone-test-xuzupuf0zebe/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt"
2026/09/18 02:38:56 DEBUG : Creating new ShadeFS backend with drive: cf193d00-c783-4bab-aa7a-fd84b3700d27
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound
2026/09/18 02:38:57 DEBUG : Creating backend with remote "TestShade:rclone-test-xuzupuf0zebe/not found.txt"
2026/09/18 02:38:57 DEBUG : Creating new ShadeFS backend with drive: cf193d00-c783-4bab-aa7a-fd84b3700d27
2026/09/18 02:38:57 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-xuzupuf0zebe/not found.txt: Error from List call: HTTP error 404 (404 Not Found) returned body: "ENTRY_NOT_FOUND"
2026/09/18 02:38:57 ERROR : error listing: directory not found
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot
    fstests.go:1994: Opening root remote "TestShade:" path "rclone-test-xuzupuf0zebe" from "TestShade:rclone-test-xuzupuf0zebe"
2026/09/18 02:38:57 DEBUG : Creating backend with remote "TestShade:"
2026/09/18 02:38:57 DEBUG : Creating new ShadeFS backend with drive: cf193d00-c783-4bab-aa7a-fd84b3700d27
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/List
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR
    fstests.go:2031: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put
2026/09/18 02:38:59 DEBUG : rclone-test-xuzupuf0zebe/created from root.txt: multipart upload: starting chunk 0 size 100 offset 0/100
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove
2026/09/18 02:39:01 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:01 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:39:02 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:02 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:39:02 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:02 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:39:02 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:02 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:39:02 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:02 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:39:02 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:02 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:39:02 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:02 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:39:03 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:03 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:39:04 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:39:07 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:39:08 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:39:09 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:39:09 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:39:09 DEBUG : pacer: Reducing sleep to 40ms
=== RUN   TestIntegration/FsMkdir/FsPutFiles/PublicLink
    fstests.go:2090: FS has no PublicLinker interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/SetTier
    fstests.go:438: FS has no SetTier & GetTier interfaces
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap
    fstests.go:2195: Not a wrapping Fs
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Bucket
    fstests.go:2211: Not a bucket based backend
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectRemove
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectAbout
    fstests.go:2367: FS does not support About
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPutStream
    fstests.go:2385: FS has no PutStream interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal
    fstests.go:2431: *shade.Fs does not implement InternalTester
=== RUN   TestIntegration/FsMkdir/FsPutChunked
    fstests.go:2452: *shade.Fs does not implement SetUploadChunkSizer
=== RUN   TestIntegration/FsMkdir/FsPutRetry
=== RUN   TestIntegration/FsMkdir/FsPutRetry/Simple
=== RUN   TestIntegration/FsMkdir/FsPutRetry/Simple/Status500
2026/09/18 02:39:11 DEBUG : retry-Status500.bin: multipart upload: starting chunk 0 size 64Ki offset 0/64Ki
2026/09/18 02:39:11 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "")
2026/09/18 02:39:11 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:39:11 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:39:13 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:39:13 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:13 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:39:14 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:14 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:39:14 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:14 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:39:14 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:14 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:39:14 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:14 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:39:14 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:14 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:39:15 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:39:16 DEBUG : pacer: Reducing sleep to 320ms
=== RUN   TestIntegration/FsMkdir/FsPutRetry/Simple/NetError
2026/09/18 02:39:17 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:39:17 DEBUG : retry-NetError.bin: multipart upload: starting chunk 0 size 64Ki offset 0/64Ki
2026/09/18 02:39:17 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:39:17 DEBUG : pacer: low level retry 1/10 (error Put "https://2f0871f542c14e6bb9ff5efbf27effa0.r2.cloudflarestorage.com/shade-prod-weur/unhashed/unhashed-cf193d00c7834babaa7afd84b3700d27258f0608cecf7eef85427799c79474f6.data?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Content-Sha256=UNSIGNED-PAYLOAD&X-Amz-Credential=d2f7919b2c85403a4eb8f1021cfb8288%2F20260918%2Fus-east-1%2Fs3%2Faws4_request&X-Amz-Date=20260918T023917Z&X-Amz-Expires=604800&X-Amz-Signature=c4377ad02a4a474dd0a298b3e3730d3c94f8c88d154345f2feee724e9e3fd4d8&X-Amz-SignedHeaders=host&partNumber=1&uploadId=AG5un0a9DWPEtgA-9UaVV-_V43eSv2GYzRCb7zagph7O1EO7qYL7QyeCUYDSArBY-fuTDw2ygj6oH7AGwrSjEAmB7q7yCxnAoyqRFoAG3fLH57LM6Od_1T6krMGn5atHvREM1G26Qm4PvaHdA3sW1gTqsxHqTIjhibHDIGLSk51m-eSShvWBqPN5ngxvgHZfNOZ2q88FyJVitaizGyqhatNu8J9MPmelw-x7R-ckz9ua1TbluONVKXsFzgDnj_JOkpdbf85wXaELJBjEP_TsUTThilCJ9arJnMn8ydQPicO1o5M11QuHfPmD0Zj9DuTO9WTIon54O6brkkawmG0ByOE&x-id=UploadPart": write tcp: unexpected EOF)
2026/09/18 02:39:17 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:39:18 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:39:19 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:39:19 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:39:19 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:39:19 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:19 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:39:20 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:20 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:39:20 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:20 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:39:20 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:20 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:39:20 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:39:20 DEBUG : pacer: Reducing sleep to 40ms
=== RUN   TestIntegration/FsMkdir/FsPutRetry/Chunked
    fstests.go:2697: *shade.Fs does not implement SetUploadChunkSizer
=== RUN   TestIntegration/FsMkdir/FsPutShortEOF
=== RUN   TestIntegration/FsMkdir/FsPutShortEOF/Simple
2026/09/18 02:39:21 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:39:21 DEBUG : short-eof.bin: multipart upload: starting chunk 0 size 5Ki offset 0/10Ki
2026/09/18 02:39:21 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:39:21 DEBUG : short-eof.bin: Cancelling multipart upload
    fstests.go:2784: Put returned an error (acceptable): multipart upload: expected 10240 bytes in input, but got 5120: unexpected EOF
=== RUN   TestIntegration/FsMkdir/FsPutShortEOF/Chunked
    fstests.go:2828: *shade.Fs does not implement SetUploadChunkSizer
=== RUN   TestIntegration/FsMkdir/FsCopyChunked
    fstests.go:2870: FS has no Copier interface
=== RUN   TestIntegration/FsMkdir/FsUploadUnknownSize
=== RUN   TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize
2026/09/18 02:39:22 NOTICE: Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-xuzupuf0zebe: Streaming uploads using chunk size 64Mi will have maximum file size of 625Gi
2026/09/18 02:39:22 DEBUG : unknown-size-put.txt: multipart upload: starting chunk 0 size 100 offset 0/off
2026/09/18 02:39:24 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:24 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:39:24 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:24 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:39:24 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:24 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:39:24 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:24 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:39:24 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:24 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:39:24 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:24 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:39:25 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:25 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:39:25 DEBUG : pacer: Reducing sleep to 640ms
=== RUN   TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize
2026/09/18 02:39:27 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:39:27 DEBUG : unknown-size-update.txt: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/18 02:39:27 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:39:28 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:39:29 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:39:30 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:39:30 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:39:30 DEBUG : unknown-size-update.txt: multipart upload: starting chunk 0 size 200 offset 0/off
2026/09/18 02:39:32 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:39:32 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:32 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:39:32 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:32 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:39:32 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:32 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:39:32 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:32 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:39:33 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:33 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:39:33 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:33 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:39:34 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:34 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:39:35 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:35 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:39:37 DEBUG : pacer: Reducing sleep to 2.56s
=== RUN   TestIntegration/FsMkdir/FsRootCollapse
2026/09/18 02:39:37 DEBUG : Creating backend with remote "TestShade:rclone-test-xuzupuf0zebe/deeper/nonexisting/directory"
2026/09/18 02:39:37 DEBUG : Creating new ShadeFS backend with drive: cf193d00-c783-4bab-aa7a-fd84b3700d27
=== RUN   TestIntegration/FsMkdir/FsDirSetModTime
    fstests.go:3019: FS has no DirSetModTime interface
=== RUN   TestIntegration/FsMkdir/FsMkdirMetadata
    fstests.go:3062: FS has no MkdirMetadata interface
=== RUN   TestIntegration/FsMkdir/FsDirectory
    fstests.go:3119: FS has no Directory methods and doesn't Wrap
2026/09/18 02:39:38 DEBUG : Waiting for deletions to finish
2026/09/18 02:39:43 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:39:45 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:39:46 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:39:47 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:39:47 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:39:47 DEBUG : pacer: Reducing sleep to 40ms
2026/09/18 02:39:47 INFO  : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleted
2026/09/18 02:39:48 DEBUG : pacer: Reducing sleep to 20ms
2026/09/18 02:39:48 DEBUG : pacer: Reducing sleep to 10ms
2026/09/18 02:39:48 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:48 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/18 02:39:48 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:48 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/18 02:39:49 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:49 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/18 02:39:49 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:49 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:39:49 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:49 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:39:49 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:49 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:39:49 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:39:49 DEBUG : removing 1 level 4 directories
2026/09/18 02:39:49 INFO  : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠: Removing directory
2026/09/18 02:39:50 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:39:50 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:39:50 DEBUG : removing 1 level 3 directories
2026/09/18 02:39:50 INFO  : hello? sausage/êé/Hello, 世界: Removing directory
2026/09/18 02:39:50 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:50 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/18 02:39:51 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:51 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/18 02:39:51 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:51 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:39:51 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:51 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:39:52 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:52 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:39:53 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:39:53 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:39:56 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:40:01 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:40:01 DEBUG : removing 1 level 2 directories
2026/09/18 02:40:01 INFO  : hello? sausage/êé: Removing directory
2026/09/18 02:40:03 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:40:04 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:40:04 DEBUG : removing 1 level 1 directories
2026/09/18 02:40:04 INFO  : hello? sausage: Removing directory
2026/09/18 02:40:05 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:40:05 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/18 02:40:05 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:40:05 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/18 02:40:06 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:40:06 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:40:07 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:40:07 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:40:10 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:40:15 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:40:15 DEBUG : removing 1 level 0 directories
2026/09/18 02:40:15 INFO  : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-xuzupuf0zebe: Removing directory
2026/09/18 02:40:18 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:40:18 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/18 02:40:19 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/18 02:40:19 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/18 02:40:22 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/18 02:40:27 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/18 02:40:29 DEBUG : pacer: Reducing sleep to 640ms
2026/09/18 02:40:29 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-xuzupuf0zebe: Error from List call: HTTP error 404 (404 Not Found) returned body: "ENTRY_NOT_FOUND"
2026/09/18 02:40:29 ERROR : error listing: directory not found
2026/09/18 02:40:29 DEBUG : Waiting for deletions to finish
2026/09/18 02:40:30 DEBUG : pacer: Reducing sleep to 320ms
2026/09/18 02:40:30 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-xuzupuf0zebe: Error from List call: HTTP error 404 (404 Not Found) returned body: "ENTRY_NOT_FOUND"
2026/09/18 02:40:30 ERROR : error listing: directory not found
2026/09/18 02:40:31 DEBUG : pacer: Reducing sleep to 160ms
2026/09/18 02:40:31 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-xuzupuf0zebe: Error from List call: HTTP error 404 (404 Not Found) returned body: "ENTRY_NOT_FOUND"
2026/09/18 02:40:31 ERROR : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-xuzupuf0zebe: Failed to list "": directory not found
2026/09/18 02:40:31 DEBUG : removing 1 level 0 directories
2026/09/18 02:40:31 INFO  : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-xuzupuf0zebe: Removing directory
2026/09/18 02:40:31 DEBUG : pacer: Reducing sleep to 80ms
2026/09/18 02:40:31 ERROR : Failed to rmdir: HTTP error 404 (404 Not Found) returned body: "ENTRY_NOT_FOUND"
=== NAME  TestIntegration/FsMkdir
    fstests.go:3211: Warning: this should produce fs.ErrorDirNotFound
=== RUN   TestIntegration/FsShutdown
    fstests.go:3225: Shutdown method not supported
--- PASS: TestIntegration (790.32s)
    --- SKIP: TestIntegration/FsCheckWrap (0.00s)
    --- SKIP: TestIntegration/FsCommand (0.00s)
    --- PASS: TestIntegration/FsRmdirNotFound (0.15s)
    --- PASS: TestIntegration/FsString (0.00s)
    --- PASS: TestIntegration/FsName (0.00s)
    --- PASS: TestIntegration/FsRoot (0.00s)
    --- PASS: TestIntegration/FsRmdirEmpty (7.69s)
    --- PASS: TestIntegration/FsMkdir (776.40s)
        --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (32.67s)
        --- PASS: TestIntegration/FsMkdir/FsListEmpty (5.13s)
        --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (2.56s)
        --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (1.28s)
        --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsEncoding (494.40s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (19.48s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (23.14s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (24.65s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (30.54s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (30.63s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (30.63s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (28.09s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (24.08s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (30.51s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (28.60s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (25.30s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (30.47s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (30.53s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (30.67s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (30.51s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (30.71s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (25.43s)
            --- SKIP: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (19.80s)
        --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (1.93s)
        --- PASS: TestIntegration/FsMkdir/FsPutError (1.74s)
        --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (6.88s)
        --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsOpenChunkWriter (26.54s)
        --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsPutFiles (115.55s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (0.74s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.75s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.60s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (0.31s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.30s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.30s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (0.75s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.15s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (0.75s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.15s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsPurge (0.00s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (46.57s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (14.34s)
                --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (18.10s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.15s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.15s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.14s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.15s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.15s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.15s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.14s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (0.15s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (0.15s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (0.15s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.15s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.46s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.44s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (1.22s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.44s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint (2.29s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read (0.27s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read (0.27s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read (0.26s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end (0.26s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh (0.39s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (2.80s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.15s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (0.31s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (0.31s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (12.33s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.74s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.15s)
                --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (11.29s)
                    --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (8.11s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/PublicLink (0.00s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Bucket (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (1.03s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (0.00s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal (0.00s)
        --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsPutRetry (9.86s)
            --- PASS: TestIntegration/FsMkdir/FsPutRetry/Simple (9.86s)
                --- PASS: TestIntegration/FsMkdir/FsPutRetry/Simple/Status500 (5.94s)
                --- PASS: TestIntegration/FsMkdir/FsPutRetry/Simple/NetError (3.92s)
            --- SKIP: TestIntegration/FsMkdir/FsPutRetry/Chunked (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsPutShortEOF (1.42s)
            --- PASS: TestIntegration/FsMkdir/FsPutShortEOF/Simple (1.42s)
            --- SKIP: TestIntegration/FsMkdir/FsPutShortEOF/Chunked (0.00s)
        --- SKIP: TestIntegration/FsMkdir/FsCopyChunked (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (15.75s)
            --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (3.76s)
            --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (11.99s)
        --- PASS: TestIntegration/FsMkdir/FsRootCollapse (0.31s)
        --- SKIP: TestIntegration/FsMkdir/FsDirSetModTime (0.00s)
        --- SKIP: TestIntegration/FsMkdir/FsMkdirMetadata (0.00s)
        --- SKIP: TestIntegration/FsMkdir/FsDirectory (0.00s)
    --- SKIP: TestIntegration/FsShutdown (0.00s)
PASS
ok  	github.com/rclone/rclone/backend/shade	790.377s
"go test -v -timeout 1h0m0s -remote TestShade: -verbose" - Finished OK in 13m11.572600445s (try 1/5)