"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestApplyTransforms|TestCaseInsensitiveMoveFileDryRun|TestCat|TestCheckSumDownload|TestCopyDirMetadata|TestCopyFileCompareDest|TestCopyURL|TestCopyURLDownloadHeaders|TestCount|TestDelete|TestDirMove|TestDirsEqual|TestGetFsInfo|TestHashSums|TestMaxDelete|TestMaxDeleteSize|TestMaxDeleteSizeLargeFile|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRcatSizeUploadHeaders|TestReadFile|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestSetDirModTime|TestTouchDir)$|^TestCheck$/^1$|^TestCheckSizeOnly$/^6$|^TestListJSON$/^(Default|DirsOnly|FilesOnly|HashTypes|Metadata|NoMimeType|NoModTime|Recurse|ShowHash)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$|^TestStatJSON$/^Root$'" - Starting (try 2/5)
2026/07/30 02:40:03 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-boviwoy3pefe"
2026/07/30 02:40:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/30 02:40:03 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe': Using cached web token
2026/07/30 02:40:03 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:40:03 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:40:03 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:40:03 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:40:04 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:40:04 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:40:04 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:40:04 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:40:06 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:40:08 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:40:10 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:40:10 DEBUG : Creating backend with remote "/tmp/rclone1634275649"
=== RUN   TestCheck
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:40:12 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:40:14 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:40:14 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:40:15 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:40:16 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:40:17 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:40:17 DEBUG : pacer: Reducing sleep to 200ms
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
=== RUN   TestCheck/1
2026/07/30 02:40:29 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:40:29 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:40:32 DEBUG : remotepotato: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:40:33 DEBUG : remotepotato: Trying to read object after upload: try again in 200ms (2/10)
=== NAME  TestCheck
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 7s just to make sure
--- PASS: TestCheck (39.75s)
    --- PASS: TestCheck/1 (0.28s)
=== RUN   TestCheckSizeOnly
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:40:52 DEBUG : rutabaga: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:40:52 DEBUG : rutabaga: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:40:53 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:40:53 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:40:53 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:40:53 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:40:54 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:40:54 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:40:55 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:40:55 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:40:56 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:40:58 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:41:00 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:41:02 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:41:04 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:41:06 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
    run.go:299: Retry Put of "empty space" to Linkbox root 'rclone-test-boviwoy3pefe': 1/10 (Update err in unmarshaling response: invalid character '<' looking for beginning of value)
2026/07/30 02:41:08 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:41:10 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:41:13 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:41:13 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:41:13 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:41:14 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:41:15 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:41:15 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:41:16 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:41:17 DEBUG : pacer: Reducing sleep to 200ms
2026/07/30 02:41:17 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:41:18 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:41:18 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:41:21 DEBUG : remotepotato: Trying to read object after upload: try again in 100ms (1/10)
=== RUN   TestCheckSizeOnly/6
=== NAME  TestCheckSizeOnly
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestCheckSizeOnly (45.01s)
    --- PASS: TestCheckSizeOnly/6 (0.20s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:41:35 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-boviwoy3pefe/data"
2026/07/30 02:41:35 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe/data': Using cached web token
2026/07/30 02:41:43 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:41:43 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:41:43 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10)
=== RUN   TestCheckSumDownload/subtest1
2026/07/30 02:41:48 DEBUG : data/potato: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:41:48 DEBUG : test.sum: Update: removing old file
2026/07/30 02:41:51 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:41:51 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:41:51 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:41:51 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:41:52 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:41:52 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:41:53 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:41:53 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:41:54 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:41:56 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:41:58 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:42:00 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:42:02 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:42:04 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
=== NAME  TestCheckSumDownload
    run.go:299: Retry Put of "test.sum" to Linkbox root 'rclone-test-boviwoy3pefe': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value)
2026/07/30 02:42:06 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:42:08 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:42:10 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:42:10 DEBUG : test.sum: Update: removing old file
2026/07/30 02:42:14 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:42:14 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:42:16 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:42:16 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:42:17 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:42:18 DEBUG : pacer: Reducing sleep to 266.967773ms
=== RUN   TestCheckSumDownload/subtest2
2026/07/30 02:42:19 DEBUG : test.sum: Update: removing old file
2026/07/30 02:42:22 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:42:22 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:42:22 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10)
=== RUN   TestCheckSumDownload/subtest3
2026/07/30 02:42:24 DEBUG : test.sum: Update: removing old file
2026/07/30 02:42:27 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:42:28 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:42:28 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:42:29 DEBUG : test.sum: Trying to read object after upload: try again in 800ms (4/10)
2026/07/30 02:42:30 DEBUG : test.sum: Trying to read object after upload: try again in 1.6s (5/10)
2026/07/30 02:42:31 DEBUG : test.sum: Trying to read object after upload: try again in 3.2s (6/10)
2026/07/30 02:42:35 DEBUG : test.sum: Trying to read object after upload: try again in 6.4s (7/10)
2026/07/30 02:42:41 DEBUG : test.sum: Trying to read object after upload: try again in 12.8s (8/10)
2026/07/30 02:42:54 DEBUG : test.sum: Trying to read object after upload: try again in 25.6s (9/10)
2026/07/30 02:43:20 DEBUG : test.sum: Trying to read object after upload: try again in 51.2s (10/10)
=== NAME  TestCheckSumDownload
    run.go:303: Failed to put "test.sum" to "Linkbox root 'rclone-test-boviwoy3pefe'": object not found
--- FAIL: TestCheckSumDownload (162.56s)
    --- PASS: TestCheckSumDownload/subtest1 (1.14s)
    --- PASS: TestCheckSumDownload/subtest2 (0.77s)
    --- PASS: TestCheckSumDownload/subtest3 (0.67s)
=== RUN   TestApplyTransforms
2026/07/30 02:44:18 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-yewimer1yiyi"
2026/07/30 02:44:18 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/30 02:44:18 DEBUG : Linkbox root 'rclone-test-yewimer1yiyi': Using cached web token
2026/07/30 02:44:18 DEBUG : Creating backend with remote "/tmp/rclone2296978513"
    run.go:198: Remote "Linkbox root 'rclone-test-yewimer1yiyi'", Local "Local file system at /tmp/rclone2296978513", Modify Window "876000h0m0s"
upper checkfile vs. lower remote (without normalization)
2026/07/30 02:44:23 ERROR : hello, world!: sum not found
2026/07/30 02:44:23 ERROR : HELLO, WORLD!: file not in Linkbox root 'rclone-test-yewimer1yiyi'
2026/07/30 02:44:23 NOTICE: Linkbox root 'rclone-test-yewimer1yiyi': 1 files missing
2026/07/30 02:44:23 NOTICE: 1 hashes missing
2026/07/30 02:44:23 NOTICE: Linkbox root 'rclone-test-yewimer1yiyi': 1 differences found
2026/07/30 02:44:23 NOTICE: Linkbox root 'rclone-test-yewimer1yiyi': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/07/30 02:44:23 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/07/30 02:44:23 NOTICE: Linkbox root 'rclone-test-yewimer1yiyi': 0 differences found
2026/07/30 02:44:23 NOTICE: Linkbox root 'rclone-test-yewimer1yiyi': 1 matching files
2026/07/30 02:44:23 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-siziked1ziya"
2026/07/30 02:44:23 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/30 02:44:23 DEBUG : Linkbox root 'rclone-test-siziked1ziya': Using cached web token
2026/07/30 02:44:24 DEBUG : Creating backend with remote "/tmp/rclone1098697716"
    run.go:198: Remote "Linkbox root 'rclone-test-siziked1ziya'", Local "Local file system at /tmp/rclone1098697716", Modify Window "876000h0m0s"
lower checkfile vs. upper remote (without normalization)
2026/07/30 02:44:28 ERROR : HELLO, WORLD!: sum not found
2026/07/30 02:44:28 ERROR : hello, world!: file not in Linkbox root 'rclone-test-siziked1ziya'
2026/07/30 02:44:28 NOTICE: Linkbox root 'rclone-test-siziked1ziya': 1 files missing
2026/07/30 02:44:28 NOTICE: 1 hashes missing
2026/07/30 02:44:28 NOTICE: Linkbox root 'rclone-test-siziked1ziya': 1 differences found
2026/07/30 02:44:28 NOTICE: Linkbox root 'rclone-test-siziked1ziya': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/07/30 02:44:28 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/07/30 02:44:28 NOTICE: Linkbox root 'rclone-test-siziked1ziya': 0 differences found
2026/07/30 02:44:28 NOTICE: Linkbox root 'rclone-test-siziked1ziya': 1 matching files
2026/07/30 02:44:28 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-pisijoj2kowu"
2026/07/30 02:44:28 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/30 02:44:28 DEBUG : Linkbox root 'rclone-test-pisijoj2kowu': Using cached web token
2026/07/30 02:44:29 DEBUG : Creating backend with remote "/tmp/rclone3054724028"
    run.go:198: Remote "Linkbox root 'rclone-test-pisijoj2kowu'", Local "Local file system at /tmp/rclone3054724028", Modify Window "876000h0m0s"
2026/07/30 02:44:33 DEBUG : HeLlO, wOrLd!: Trying to read object after upload: try again in 100ms (1/10)
lower checkfile vs. upperlowermixed remote (without normalization)
2026/07/30 02:44:33 ERROR : HeLlO, wOrLd!: sum not found
2026/07/30 02:44:33 ERROR : hello, world!: file not in Linkbox root 'rclone-test-pisijoj2kowu'
2026/07/30 02:44:33 NOTICE: Linkbox root 'rclone-test-pisijoj2kowu': 1 files missing
2026/07/30 02:44:33 NOTICE: 1 hashes missing
2026/07/30 02:44:33 NOTICE: Linkbox root 'rclone-test-pisijoj2kowu': 1 differences found
2026/07/30 02:44:33 NOTICE: Linkbox root 'rclone-test-pisijoj2kowu': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/07/30 02:44:34 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/07/30 02:44:34 NOTICE: Linkbox root 'rclone-test-pisijoj2kowu': 0 differences found
2026/07/30 02:44:34 NOTICE: Linkbox root 'rclone-test-pisijoj2kowu': 1 matching files
2026/07/30 02:44:34 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jikeyev9vizu"
2026/07/30 02:44:34 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/30 02:44:34 DEBUG : Linkbox root 'rclone-test-jikeyev9vizu': Using cached web token
2026/07/30 02:44:35 DEBUG : Creating backend with remote "/tmp/rclone34706197"
2026/07/30 02:44:35 DEBUG : Config file has changed externally - reloading
    run.go:198: Remote "Linkbox root 'rclone-test-jikeyev9vizu'", Local "Local file system at /tmp/rclone34706197", Modify Window "876000h0m0s"
upperlowermixed checkfile vs. upper remote (without normalization)
2026/07/30 02:44:39 ERROR : HELLO, WORLD!: sum not found
2026/07/30 02:44:39 ERROR : HeLlO, wOrLd!: file not in Linkbox root 'rclone-test-jikeyev9vizu'
2026/07/30 02:44:39 NOTICE: Linkbox root 'rclone-test-jikeyev9vizu': 1 files missing
2026/07/30 02:44:39 NOTICE: 1 hashes missing
2026/07/30 02:44:39 NOTICE: Linkbox root 'rclone-test-jikeyev9vizu': 1 differences found
2026/07/30 02:44:39 NOTICE: Linkbox root 'rclone-test-jikeyev9vizu': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/07/30 02:44:39 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/07/30 02:44:39 NOTICE: Linkbox root 'rclone-test-jikeyev9vizu': 0 differences found
2026/07/30 02:44:39 NOTICE: Linkbox root 'rclone-test-jikeyev9vizu': 1 matching files
2026/07/30 02:44:39 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-roferiv9gibi"
2026/07/30 02:44:39 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/30 02:44:39 DEBUG : Linkbox root 'rclone-test-roferiv9gibi': Using cached web token
2026/07/30 02:44:40 DEBUG : Creating backend with remote "/tmp/rclone3525566097"
    run.go:198: Remote "Linkbox root 'rclone-test-roferiv9gibi'", Local "Local file system at /tmp/rclone3525566097", Modify Window "876000h0m0s"
2026/07/30 02:44:44 DEBUG : 測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:44:44 DEBUG : 測試_Русский___ě_áñ: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:44:45 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:44:45 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:44:45 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:44:45 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:44:45 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:44:45 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:44:46 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:44:46 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:44:48 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:44:50 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:44:52 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:44:54 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:44:56 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:44:58 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
    run.go:299: Retry Put of "測試_Русский___ě_áñ" to Linkbox root 'rclone-test-roferiv9gibi': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value)
2026/07/30 02:45:00 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:45:02 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:45:04 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:45:06 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:45:08 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:45:10 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:45:12 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:45:12 DEBUG : 測試_Русский___ě_áñ: Update: removing old file
2026/07/30 02:45:15 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:45:15 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:45:18 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:45:18 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:45:18 DEBUG : 測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:45:19 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:45:19 DEBUG : pacer: Reducing sleep to 266.967773ms
NFD checkfile vs. NFC remote (without normalization)
2026/07/30 02:45:19 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:45:19 ERROR : 測試_Русский___ě_áñ: sum not found
2026/07/30 02:45:19 ERROR : 測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-roferiv9gibi'
2026/07/30 02:45:19 NOTICE: Linkbox root 'rclone-test-roferiv9gibi': 1 files missing
2026/07/30 02:45:19 NOTICE: 1 hashes missing
2026/07/30 02:45:19 NOTICE: Linkbox root 'rclone-test-roferiv9gibi': 1 differences found
2026/07/30 02:45:19 NOTICE: Linkbox root 'rclone-test-roferiv9gibi': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/07/30 02:45:20 DEBUG : pacer: Reducing sleep to 200ms
2026/07/30 02:45:20 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/07/30 02:45:20 NOTICE: Linkbox root 'rclone-test-roferiv9gibi': 0 differences found
2026/07/30 02:45:20 NOTICE: Linkbox root 'rclone-test-roferiv9gibi': 1 matching files
2026/07/30 02:45:20 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-dajoqaz1bapi"
2026/07/30 02:45:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/30 02:45:20 DEBUG : Linkbox root 'rclone-test-dajoqaz1bapi': Using cached web token
2026/07/30 02:45:21 DEBUG : Creating backend with remote "/tmp/rclone3084835542"
    run.go:198: Remote "Linkbox root 'rclone-test-dajoqaz1bapi'", Local "Local file system at /tmp/rclone3084835542", Modify Window "876000h0m0s"
NFC checkfile vs. NFD remote (without normalization)
2026/07/30 02:45:25 ERROR : 測試_Русский___ě_áñ: sum not found
2026/07/30 02:45:25 ERROR : 測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-dajoqaz1bapi'
2026/07/30 02:45:25 NOTICE: Linkbox root 'rclone-test-dajoqaz1bapi': 1 files missing
2026/07/30 02:45:25 NOTICE: 1 hashes missing
2026/07/30 02:45:25 NOTICE: Linkbox root 'rclone-test-dajoqaz1bapi': 1 differences found
2026/07/30 02:45:25 NOTICE: Linkbox root 'rclone-test-dajoqaz1bapi': 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/07/30 02:45:25 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/07/30 02:45:25 NOTICE: Linkbox root 'rclone-test-dajoqaz1bapi': 0 differences found
2026/07/30 02:45:25 NOTICE: Linkbox root 'rclone-test-dajoqaz1bapi': 1 matching files
2026/07/30 02:45:25 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-dabimup4noku"
2026/07/30 02:45:25 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/30 02:45:25 DEBUG : Linkbox root 'rclone-test-dabimup4noku': Using cached web token
2026/07/30 02:45:26 DEBUG : Creating backend with remote "/tmp/rclone95520398"
    run.go:198: Remote "Linkbox root 'rclone-test-dabimup4noku'", Local "Local file system at /tmp/rclone95520398", Modify Window "876000h0m0s"
2026/07/30 02:45:30 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:45:30 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:45:30 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:45:31 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 800ms (4/10)
2026/07/30 02:45:32 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 1.6s (5/10)
NFDx2 checkfile vs. both remote (without normalization)
2026/07/30 02:45:34 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/07/30 02:45:34 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-dabimup4noku'
2026/07/30 02:45:34 NOTICE: Linkbox root 'rclone-test-dabimup4noku': 1 files missing
2026/07/30 02:45:34 NOTICE: 1 hashes missing
2026/07/30 02:45:34 NOTICE: Linkbox root 'rclone-test-dabimup4noku': 1 differences found
2026/07/30 02:45:34 NOTICE: Linkbox root 'rclone-test-dabimup4noku': 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/07/30 02:45:35 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/07/30 02:45:35 NOTICE: Linkbox root 'rclone-test-dabimup4noku': 0 differences found
2026/07/30 02:45:35 NOTICE: Linkbox root 'rclone-test-dabimup4noku': 1 matching files
2026/07/30 02:45:35 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-sijekak0jewi"
2026/07/30 02:45:35 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/30 02:45:35 DEBUG : Linkbox root 'rclone-test-sijekak0jewi': Using cached web token
2026/07/30 02:45:35 DEBUG : Creating backend with remote "/tmp/rclone3470938516"
    run.go:198: Remote "Linkbox root 'rclone-test-sijekak0jewi'", Local "Local file system at /tmp/rclone3470938516", Modify Window "876000h0m0s"
2026/07/30 02:45:39 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10)
NFCx2 checkfile vs. both remote (without normalization)
2026/07/30 02:45:40 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/07/30 02:45:40 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-sijekak0jewi'
2026/07/30 02:45:40 NOTICE: Linkbox root 'rclone-test-sijekak0jewi': 1 files missing
2026/07/30 02:45:40 NOTICE: 1 hashes missing
2026/07/30 02:45:40 NOTICE: Linkbox root 'rclone-test-sijekak0jewi': 1 differences found
2026/07/30 02:45:40 NOTICE: Linkbox root 'rclone-test-sijekak0jewi': 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/07/30 02:45:40 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/07/30 02:45:40 NOTICE: Linkbox root 'rclone-test-sijekak0jewi': 0 differences found
2026/07/30 02:45:40 NOTICE: Linkbox root 'rclone-test-sijekak0jewi': 1 matching files
2026/07/30 02:45:40 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jilinin7doja"
2026/07/30 02:45:40 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/30 02:45:40 DEBUG : Linkbox root 'rclone-test-jilinin7doja': Using cached web token
2026/07/30 02:45:41 DEBUG : Creating backend with remote "/tmp/rclone604847453"
    run.go:198: Remote "Linkbox root 'rclone-test-jilinin7doja'", Local "Local file system at /tmp/rclone604847453", Modify Window "876000h0m0s"
2026/07/30 02:45:45 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10)
both checkfile vs. NFDx2 remote (without normalization)
2026/07/30 02:45:46 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/07/30 02:45:46 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-jilinin7doja'
2026/07/30 02:45:46 NOTICE: Linkbox root 'rclone-test-jilinin7doja': 1 files missing
2026/07/30 02:45:46 NOTICE: 1 hashes missing
2026/07/30 02:45:46 NOTICE: Linkbox root 'rclone-test-jilinin7doja': 1 differences found
2026/07/30 02:45:46 NOTICE: Linkbox root 'rclone-test-jilinin7doja': 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/07/30 02:45:46 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/07/30 02:45:46 NOTICE: Linkbox root 'rclone-test-jilinin7doja': 0 differences found
2026/07/30 02:45:46 NOTICE: Linkbox root 'rclone-test-jilinin7doja': 1 matching files
2026/07/30 02:45:46 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-yayizub8nepu"
2026/07/30 02:45:46 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/30 02:45:46 DEBUG : Linkbox root 'rclone-test-yayizub8nepu': Using cached web token
2026/07/30 02:45:47 DEBUG : Creating backend with remote "/tmp/rclone1961268106"
    run.go:198: Remote "Linkbox root 'rclone-test-yayizub8nepu'", Local "Local file system at /tmp/rclone1961268106", Modify Window "876000h0m0s"
both checkfile vs. NFCx2 remote (without normalization)
2026/07/30 02:45:51 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/07/30 02:45:51 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-yayizub8nepu'
2026/07/30 02:45:51 NOTICE: Linkbox root 'rclone-test-yayizub8nepu': 1 files missing
2026/07/30 02:45:51 NOTICE: 1 hashes missing
2026/07/30 02:45:51 NOTICE: Linkbox root 'rclone-test-yayizub8nepu': 1 differences found
2026/07/30 02:45:51 NOTICE: Linkbox root 'rclone-test-yayizub8nepu': 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/07/30 02:45:51 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/07/30 02:45:51 NOTICE: Linkbox root 'rclone-test-yayizub8nepu': 0 differences found
2026/07/30 02:45:51 NOTICE: Linkbox root 'rclone-test-yayizub8nepu': 1 matching files
2026/07/30 02:45:51 DEBUG : Linkbox root 'rclone-test-yayizub8nepu': Purge remote
2026/07/30 02:45:53 DEBUG : Linkbox root 'rclone-test-jilinin7doja': Purge remote
2026/07/30 02:45:54 DEBUG : Linkbox root 'rclone-test-sijekak0jewi': Purge remote
2026/07/30 02:45:55 DEBUG : Linkbox root 'rclone-test-dabimup4noku': Purge remote
2026/07/30 02:45:57 DEBUG : Linkbox root 'rclone-test-dajoqaz1bapi': Purge remote
2026/07/30 02:45:58 DEBUG : Linkbox root 'rclone-test-roferiv9gibi': Purge remote
2026/07/30 02:45:59 DEBUG : Linkbox root 'rclone-test-jikeyev9vizu': Purge remote
2026/07/30 02:46:01 DEBUG : Linkbox root 'rclone-test-pisijoj2kowu': Purge remote
2026/07/30 02:46:01 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:01 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:46:01 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:01 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:46:02 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:02 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:46:02 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:02 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:46:04 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:06 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:08 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:10 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:13 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:46:13 DEBUG : Linkbox root 'rclone-test-siziked1ziya': Purge remote
2026/07/30 02:46:14 DEBUG : Linkbox root 'rclone-test-yewimer1yiyi': Purge remote
--- PASS: TestApplyTransforms (118.18s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:46:16 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-boviwoy3pefe/dst"
2026/07/30 02:46:16 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe/dst': Using cached web token
2026/07/30 02:46:17 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-boviwoy3pefe/CompareDest"
2026/07/30 02:46:17 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe/CompareDest': Using cached web token
2026/07/30 02:46:18 DEBUG : one: Need to transfer - File not found at Destination
2026/07/30 02:46:22 DEBUG : one: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:46:22 DEBUG : one: size = 3 OK
2026/07/30 02:46:22 INFO  : one: Copied (new)
2026/07/30 02:46:23 DEBUG : one: size = 5 (Local file system at /tmp/rclone1634275649)
2026/07/30 02:46:23 DEBUG : one: size = 3 (Linkbox root 'rclone-test-boviwoy3pefe/dst')
2026/07/30 02:46:23 DEBUG : one: Sizes differ
2026/07/30 02:46:23 DEBUG : one: Update: removing old file
2026/07/30 02:46:27 DEBUG : one: size = 5 OK
2026/07/30 02:46:27 INFO  : one: Copied (replaced existing)
2026/07/30 02:46:27 DEBUG : dst/one: Update: removing old file
2026/07/30 02:46:35 DEBUG : one: size = 5 (Local file system at /tmp/rclone1634275649)
2026/07/30 02:46:35 DEBUG : one: size = 3 (Linkbox root 'rclone-test-boviwoy3pefe/dst')
2026/07/30 02:46:35 DEBUG : one: Sizes differ
2026/07/30 02:46:35 DEBUG : one: size = 5 OK
2026/07/30 02:46:35 DEBUG : one: Sizes identical
2026/07/30 02:46:35 DEBUG : one: Destination found in --compare-dest, skipping
2026/07/30 02:46:39 DEBUG : two: Need to transfer - File not found at Destination
2026/07/30 02:46:39 DEBUG : two: size = 3 OK
2026/07/30 02:46:39 DEBUG : two: Sizes identical
2026/07/30 02:46:39 DEBUG : two: Destination found in --compare-dest, skipping
2026/07/30 02:46:40 DEBUG : two: Need to transfer - File not found at Destination
2026/07/30 02:46:40 DEBUG : two: size = 3 OK
2026/07/30 02:46:40 DEBUG : two: Sizes identical
2026/07/30 02:46:40 DEBUG : two: Destination found in --compare-dest, skipping
2026/07/30 02:46:41 DEBUG : two: Need to transfer - File not found at Destination
2026/07/30 02:46:41 DEBUG : two: size = 5 (Local file system at /tmp/rclone1634275649)
2026/07/30 02:46:41 DEBUG : two: size = 3 (Linkbox root 'rclone-test-boviwoy3pefe/CompareDest')
2026/07/30 02:46:41 DEBUG : two: Sizes differ
2026/07/30 02:46:44 DEBUG : two: size = 5 OK
2026/07/30 02:46:44 INFO  : two: Copied (new)
2026/07/30 02:46:52 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:52 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:46:52 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:52 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:46:52 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:52 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:46:53 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:53 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:46:55 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:57 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:46:59 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:01 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:03 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:05 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
    run.go:186: removing dir "CompareDest" failed - try 1/3: sleeping 1s: getting files failed: invalid character '<' looking for beginning of value
2026/07/30 02:47:07 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:09 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:11 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:13 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:47:16 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:47:16 DEBUG : pacer: Reducing sleep to 843.75ms
--- PASS: TestCopyFileCompareDest (60.43s)
=== RUN   TestListJSON
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:47:17 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:47:18 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:47:20 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:47:20 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:47:21 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:47:22 DEBUG : pacer: Reducing sleep to 200ms
=== RUN   TestListJSON/Default
=== RUN   TestListJSON/FilesOnly
=== RUN   TestListJSON/DirsOnly
=== RUN   TestListJSON/Recurse
=== RUN   TestListJSON/NoModTime
=== RUN   TestListJSON/NoMimeType
=== RUN   TestListJSON/ShowHash
=== RUN   TestListJSON/HashTypes
2026/07/30 02:47:26 ERROR : file1: Failed to read hash: hash type not supported
=== RUN   TestListJSON/Metadata
--- PASS: TestListJSON (14.90s)
    --- PASS: TestListJSON/Default (0.20s)
    --- PASS: TestListJSON/FilesOnly (0.21s)
    --- PASS: TestListJSON/DirsOnly (0.21s)
    --- PASS: TestListJSON/Recurse (0.41s)
    --- PASS: TestListJSON/NoModTime (0.21s)
    --- PASS: TestListJSON/NoMimeType (0.20s)
    --- PASS: TestListJSON/ShowHash (0.20s)
    --- PASS: TestListJSON/HashTypes (0.21s)
    --- PASS: TestListJSON/Metadata (0.20s)
=== RUN   TestStatJSON
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
=== RUN   TestStatJSON/Root
--- PASS: TestStatJSON (11.13s)
    --- PASS: TestStatJSON/Root (0.21s)
=== RUN   TestHashSums
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:47:49 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:49 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:47:50 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:50 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:47:50 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:50 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:47:51 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:51 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:47:52 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:54 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:56 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:47:58 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:48:00 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:48:02 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:48:02 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value
    fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value
--- FAIL: TestHashSums (20.14s)
=== RUN   TestCount
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:48:04 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:48:06 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:48:08 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:48:10 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:48:13 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:48:15 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:48:17 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:48:17 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:48:18 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:48:19 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:48:21 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:48:21 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:48:21 DEBUG : pacer: Reducing sleep to 200ms
--- PASS: TestCount (28.77s)
=== RUN   TestDelete
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:48:38 DEBUG : Waiting for deletions to finish
2026/07/30 02:48:38 DEBUG : large: Excluded (Size Filter)
2026/07/30 02:48:38 DEBUG : large: Excluded
2026/07/30 02:48:39 INFO  : medium: Deleted
2026/07/30 02:48:39 INFO  : small: Deleted
--- PASS: TestDelete (10.09s)
=== RUN   TestMaxDelete
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:48:48 DEBUG : Waiting for deletions to finish
2026/07/30 02:48:48 ERROR : small: Got fatal error on delete: --max-delete threshold reached
2026/07/30 02:48:49 INFO  : large: Deleted
2026/07/30 02:48:49 INFO  : medium: Deleted
--- PASS: TestMaxDelete (10.45s)
=== RUN   TestMaxDeleteSizeLargeFile
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:48:56 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:48:56 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:48:56 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:48:56 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:48:56 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:48:56 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:48:57 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:48:57 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:48:59 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:01 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:03 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:05 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:07 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:09 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
    run.go:299: Retry Put of "medium" to Linkbox root 'rclone-test-boviwoy3pefe': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value)
2026/07/30 02:49:11 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:13 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:49:13 DEBUG : medium: Update: removing old file
2026/07/30 02:49:16 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:49:16 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:49:19 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:49:19 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:49:20 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:49:20 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:49:21 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:49:22 DEBUG : pacer: Reducing sleep to 200ms
2026/07/30 02:49:22 DEBUG : Waiting for deletions to finish
2026/07/30 02:49:22 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached
2026/07/30 02:49:23 INFO  : medium: Deleted
2026/07/30 02:49:24 INFO  : small: Deleted
--- PASS: TestMaxDeleteSizeLargeFile (34.24s)
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:49:32 DEBUG : Waiting for deletions to finish
2026/07/30 02:49:33 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached
2026/07/30 02:49:34 INFO  : large: Deleted
2026/07/30 02:49:34 INFO  : medium: Deleted
--- PASS: TestMaxDeleteSize (10.47s)
=== RUN   TestReadFile
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
--- PASS: TestReadFile (4.79s)
=== RUN   TestCat
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:49:47 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:47 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:49:47 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:47 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:49:47 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:47 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:49:48 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:48 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:49:50 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:52 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:54 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:56 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:49:58 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:00 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:00 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value
    operations_test.go:562: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:562
        	Error:      	Received unexpected error:
        	            	getting files failed: invalid character '<' looking for beginning of value
        	Test:       	TestCat
2026/07/30 02:50:02 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:04 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:06 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:08 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:10 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:12 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:14 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:50:17 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:50:19 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:50:19 DEBUG : pacer: Reducing sleep to 632.8125ms
--- FAIL: TestCat (37.63s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:50:20 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:50:21 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:50:22 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:50:23 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:50:23 DEBUG : pacer: Reducing sleep to 200ms
2026/07/30 02:50:27 INFO  : A2: Making directory
2026/07/30 02:50:28 INFO  : A1/B2: Making directory
2026/07/30 02:50:30 INFO  : A1/B2/C2: Making directory
2026/07/30 02:50:31 INFO  : A1/B1/C3: Making directory
2026/07/30 02:50:33 INFO  : A3: Making directory
2026/07/30 02:50:34 INFO  : A3/B3: Making directory
2026/07/30 02:50:36 INFO  : A3/B3/C4: Making directory
2026/07/30 02:50:42 DEBUG : removing 1 level 3 directories
2026/07/30 02:50:42 INFO  : A3/B3/C4: Removing directory
2026/07/30 02:50:46 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:46 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:50:46 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:46 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:50:47 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:47 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:50:48 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:48 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:50:49 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:51 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:53 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:55 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:57 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:50:59 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:51:01 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:51:03 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:51:05 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:51:07 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:51:09 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:51:11 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:51:13 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:51:15 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:51:17 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:51:19 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:51:20 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:51:21 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:51:21 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:51:22 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:51:22 DEBUG : removing 2 level 3 directories
2026/07/30 02:51:22 INFO  : A1/B2/C2: Removing directory
2026/07/30 02:51:22 INFO  : A1/B1/C3: Removing directory
2026/07/30 02:51:22 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:51:23 DEBUG : pacer: Reducing sleep to 200ms
2026/07/30 02:51:24 DEBUG : removing 2 level 2 directories
2026/07/30 02:51:24 INFO  : A3/B3: Removing directory
2026/07/30 02:51:24 INFO  : A1/B2: Removing directory
2026/07/30 02:51:24 ERROR : A1/B2: Failed to rmdir: directory not empty
2026/07/30 02:51:26 DEBUG : removing 2 level 1 directories
2026/07/30 02:51:26 INFO  : A3: Removing directory
2026/07/30 02:51:26 INFO  : A2: Removing directory
2026/07/30 02:51:26 ERROR : A3: Failed to rmdir: directory not empty
    operations_test.go:705: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:705
        	Error:      	Received unexpected error:
        	            	failed to remove directories: 2 errors: last error: directory not empty
        	Test:       	TestRmdirsNoLeaveRoot
    run.go:186: removing dir "A2" failed - try 1/3: sleeping 1s: directory not found
    run.go:186: removing dir "A2" failed - try 2/3: sleeping 1.5s: directory not found
    run.go:186: removing dir "A2" failed: directory not found
--- FAIL: TestRmdirsNoLeaveRoot (84.15s)
=== RUN   TestRmdirsLeaveRoot
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:51:43 INFO  : A1: Making directory
2026/07/30 02:51:44 INFO  : A1/B1: Making directory
2026/07/30 02:51:46 INFO  : A1/B1/C1: Making directory
2026/07/30 02:51:49 DEBUG : removing 1 level 3 directories
2026/07/30 02:51:49 INFO  : A1/B1/C1: Removing directory
2026/07/30 02:51:51 DEBUG : removing 1 level 2 directories
2026/07/30 02:51:51 INFO  : A1/B1: Removing directory
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestRmdirsLeaveRoot (19.46s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:52:02 INFO  : A1: Making directory
2026/07/30 02:52:02 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:02 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:52:03 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:03 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:52:03 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:03 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:52:04 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:04 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:52:05 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:07 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:09 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:11 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:13 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:16 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:52:19 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:52:19 INFO  : A1/B1: Making directory
2026/07/30 02:52:19 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:52:21 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:52:21 INFO  : A1/B1/C1: Making directory
2026/07/30 02:52:22 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:52:23 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:52:24 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:52:24 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:52:24 DEBUG : pacer: Reducing sleep to 200ms
2026/07/30 02:52:25 DEBUG : removing 1 level 3 directories
2026/07/30 02:52:25 INFO  : A1/B1/C1: Removing directory
2026/07/30 02:52:27 DEBUG : removing 1 level 2 directories
2026/07/30 02:52:27 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (28.54s)
=== RUN   TestCopyURL
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:52:33 DEBUG : file1: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:52:34 DEBUG : filename.txt: File name found in url
2026/07/30 02:52:36 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2026/07/30 02:52:38 DEBUG : headerfilename.txt: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:52:38 DEBUG : headerfilename.txt: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:52:39 DEBUG : headerfilename.txt: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:52:39 DEBUG : headerfilename.txt: Trying to read object after upload: try again in 800ms (4/10)
2026/07/30 02:52:42 DEBUG : file2: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:52:43 DEBUG : file2: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:52:43 DEBUG : file2: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:52:44 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:44 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:52:45 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:45 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:52:45 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:45 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:52:46 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:46 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:52:47 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:49 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:51 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:53 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:55 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:52:57 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
    run.go:172: removing file "file1" failed - try 1/3: sleeping 1s: could not Remove: invalid character '<' looking for beginning of value
2026/07/30 02:52:59 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:53:01 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:53:03 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:53:05 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:53:07 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:53:09 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:53:11 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:53:13 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:53:15 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:53:19 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:53:21 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:53:22 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:53:23 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:53:24 DEBUG : pacer: Reducing sleep to 474.609375ms
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
2026/07/30 02:53:25 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:53:25 DEBUG : pacer: Reducing sleep to 266.967773ms
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestCopyURL (60.32s)
=== RUN   TestCopyURLDownloadHeaders
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:53:32 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:53:32 DEBUG : pacer: Reducing sleep to 200ms
2026/07/30 02:53:33 DEBUG : file1: Trying to read object after upload: try again in 100ms (1/10)
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:301: Flushing the directory cache
    fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:301: Flushing the directory cache
    fstest.go:305: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406
        	            				/usr/local/go/src/testing/testing.go:1317
        	            				/usr/local/go/src/testing/testing.go:1667
        	            				/usr/local/go/src/testing/testing.go:2030
        	Error:      	Should be true
        	Test:       	TestCopyURLDownloadHeaders
        	Messages:   	listing wrong, want
        	            	   got
        	            	  file1 (14)
    fstest.go:191: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406
        	            				/usr/local/go/src/testing/testing.go:1317
        	            				/usr/local/go/src/testing/testing.go:1667
        	            				/usr/local/go/src/testing/testing.go:2030
        	Error:      	Should be true
        	Test:       	TestCopyURLDownloadHeaders
        	Messages:   	Unexpected file "file1"
--- FAIL: TestCopyURLDownloadHeaders (10.79s)
=== RUN   TestMoveFile
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:53:43 DEBUG : file1: Need to transfer - File not found at Destination
2026/07/30 02:53:46 DEBUG : sub/file2: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:53:47 DEBUG : sub/file2: size = 14 OK
2026/07/30 02:53:47 INFO  : file1: Copied (new) to: sub/file2
2026/07/30 02:53:47 INFO  : file1: Deleted
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:301: Flushing the directory cache
    fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:301: Flushing the directory cache
    fstest.go:305: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:965
        	Error:      	Should be true
        	Test:       	TestMoveFile
        	Messages:   	listing wrong, want
        	            	  sub/file2 (14) got
        	            	  file1 (14), sub/file2 (14)
    fstest.go:191: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:965
        	            				/usr/local/go/src/runtime/asm_amd64.s:1771
        	Error:      	Should be true
        	Test:       	TestMoveFile
        	Messages:   	Unexpected file "file1"
2026/07/30 02:53:56 DEBUG : sub/file2: size = 14 OK
2026/07/30 02:53:56 DEBUG : file1: Sizes identical
2026/07/30 02:53:56 DEBUG : file1: Unchanged skipping
2026/07/30 02:53:56 INFO  : file1: Deleted
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:301: Flushing the directory cache
    fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:301: Flushing the directory cache
    fstest.go:305: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:973
        	Error:      	Should be true
        	Test:       	TestMoveFile
        	Messages:   	listing wrong, want
        	            	  sub/file2 (14) got
        	            	  file1 (14), sub/file2 (14)
    fstest.go:191: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:973
        	            				/usr/local/go/src/runtime/asm_amd64.s:1771
        	Error:      	Should be true
        	Test:       	TestMoveFile
        	Messages:   	Unexpected file "file1"
2026/07/30 02:54:05 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe': don't need to copy/move sub/file2, it is already at target location
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
2026/07/30 02:54:08 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:54:08 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:54:08 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:54:08 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:54:08 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:54:08 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:54:09 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:54:09 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:54:11 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:54:13 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:54:15 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:54:17 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:54:19 DEBUG : pacer: Reducing sleep to 1.5s
    fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:301: Flushing the directory cache
2026/07/30 02:54:21 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:54:23 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:54:24 DEBUG : pacer: Reducing sleep to 632.8125ms
    fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:301: Flushing the directory cache
    fstest.go:305: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:978
        	Error:      	Should be true
        	Test:       	TestMoveFile
        	Messages:   	listing wrong, want
        	            	  sub/file2 (14) got
        	            	  file1 (14), sub/file2 (14)
    fstest.go:191: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:978
        	            				/usr/local/go/src/runtime/asm_amd64.s:1771
        	Error:      	Should be true
        	Test:       	TestMoveFile
        	Messages:   	Unexpected file "file1"
2026/07/30 02:54:28 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:54:29 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:54:30 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:54:30 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:54:32 DEBUG : pacer: Reducing sleep to 200ms
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- FAIL: TestMoveFile (59.10s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:54:41 DEBUG : file1: Need to transfer - File not found at Destination
2026/07/30 02:54:44 DEBUG : file1: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:54:44 DEBUG : file1: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:54:44 DEBUG : file1: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:54:45 DEBUG : file1: Trying to read object after upload: try again in 800ms (4/10)
2026/07/30 02:54:46 DEBUG : file1: size = 14 OK
2026/07/30 02:54:46 INFO  : file1: Copied (new)
2026/07/30 02:54:46 INFO  : file1: Deleted
2026/07/30 02:54:46 DEBUG : file1: Destination exists, skipping
2026/07/30 02:54:46 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestMoveFileWithIgnoreExisting (14.58s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:54:58 DEBUG : hello: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:54:58 DEBUG : hello: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:54:59 DEBUG : hello: Trying to read object after upload: try again in 400ms (3/10)
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
2026/07/30 02:55:07 NOTICE: hello: Skipped rename to HELLO as --dry-run is set
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestCaseInsensitiveMoveFileDryRun (20.89s)
=== RUN   TestMoveFileBackupDir
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
    operations_test.go:1063: Skipping test as remote does not support server-side move or copy
2026/07/30 02:55:17 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:55:17 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:55:17 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:55:17 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:55:17 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:55:17 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:55:18 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:55:18 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:55:20 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:55:22 DEBUG : pacer: Reducing sleep to 1.125s
--- SKIP: TestMoveFileBackupDir (5.21s)
=== RUN   TestDirMove
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:55:23 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:55:26 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:55:26 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:55:26 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:55:28 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:55:28 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:55:28 DEBUG : A1/one: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:55:29 DEBUG : pacer: Reducing sleep to 200ms
2026/07/30 02:55:29 DEBUG : A1/one: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:55:29 DEBUG : A1/one: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:55:30 DEBUG : A1/one: Trying to read object after upload: try again in 800ms (4/10)
2026/07/30 02:55:33 DEBUG : A1/two: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:55:37 DEBUG : A1/B1/three: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:55:40 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:55:41 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:55:41 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:55:42 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 800ms (4/10)
2026/07/30 02:55:46 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:55:47 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:55:47 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:55:48 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 800ms (4/10)
2026/07/30 02:55:49 INFO  : A1/B2: Making directory
2026/07/30 02:55:50 INFO  : A1/B1/C3: Making directory
2026/07/30 02:56:07 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:56:07 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:56:07 DEBUG : pacer: Reducing sleep to 300ms
2026/07/30 02:56:07 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:56:07 DEBUG : pacer: Rate limited, increasing sleep to 600ms
2026/07/30 02:56:07 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:56:07 DEBUG : pacer: Rate limited, increasing sleep to 1.2s
2026/07/30 02:56:08 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:56:08 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:56:09 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:56:11 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:56:13 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:56:15 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:56:17 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:56:19 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:56:22 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:56:23 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:56:25 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:56:26 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:56:27 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:56:27 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:56:27 DEBUG : A2/one: size = 3 OK
2026/07/30 02:56:27 INFO  : A1/one: Copied (new) to: A2/one
2026/07/30 02:56:29 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:56:29 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:56:29 DEBUG : pacer: Reducing sleep to 200ms
2026/07/30 02:56:29 DEBUG : A2/B1/three: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:56:30 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:56:30 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:56:30 DEBUG : A2/B1/three: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:56:30 INFO  : A1/one: Deleted
2026/07/30 02:56:30 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:56:31 DEBUG : A2/two: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:56:31 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:56:31 DEBUG : A2/B1/three: size = 5 OK
2026/07/30 02:56:31 INFO  : A1/B1/three: Copied (new) to: A2/B1/three
2026/07/30 02:56:31 DEBUG : A2/B1/C1/four: size = 4 OK
2026/07/30 02:56:31 INFO  : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four
2026/07/30 02:56:31 DEBUG : A2/two: size = 3 OK
2026/07/30 02:56:31 INFO  : A1/two: Copied (new) to: A2/two
2026/07/30 02:56:32 DEBUG : A2/B1/C2/five: size = 4 OK
2026/07/30 02:56:32 INFO  : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five
2026/07/30 02:56:33 INFO  : A1/B1/three: Deleted
2026/07/30 02:56:33 INFO  : A1/B1/C1/four: Deleted
2026/07/30 02:56:33 INFO  : A1/two: Deleted
2026/07/30 02:56:34 INFO  : A1/B1/C2/five: Deleted
2026/07/30 02:57:00 DEBUG : A3/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:57:00 DEBUG : A3/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:57:00 DEBUG : A3/B1/C1/four: size = 4 OK
2026/07/30 02:57:00 INFO  : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four
2026/07/30 02:57:01 DEBUG : A3/one: size = 3 OK
2026/07/30 02:57:01 INFO  : A2/one: Copied (new) to: A3/one
2026/07/30 02:57:01 DEBUG : A3/B1/C2/five: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:57:01 DEBUG : A3/two: size = 3 OK
2026/07/30 02:57:01 INFO  : A2/two: Copied (new) to: A3/two
2026/07/30 02:57:01 DEBUG : A3/B1/three: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:57:02 DEBUG : A3/B1/C2/five: size = 4 OK
2026/07/30 02:57:02 INFO  : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five
2026/07/30 02:57:02 DEBUG : A3/B1/three: size = 5 OK
2026/07/30 02:57:02 INFO  : A2/B1/three: Copied (new) to: A3/B1/three
2026/07/30 02:57:02 INFO  : A2/B1/C1/four: Deleted
2026/07/30 02:57:03 INFO  : A2/one: Deleted
2026/07/30 02:57:03 INFO  : A2/two: Deleted
2026/07/30 02:57:03 INFO  : A2/B1/C2/five: Deleted
2026/07/30 02:57:04 INFO  : A2/B1/three: Deleted
    operations_test.go:1436: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1436
        	Error:      	Received unexpected error:
        	            	RenameDir rmdir: directory not empty
        	Test:       	TestDirMove
2026/07/30 02:57:16 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:57:16 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:57:16 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:57:16 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:57:16 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:57:16 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:57:17 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:57:17 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:57:19 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:57:21 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:57:24 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:57:26 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:57:26 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:57:29 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:57:29 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:57:31 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/30 02:57:31 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:57:32 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/30 02:57:32 DEBUG : pacer: Reducing sleep to 200ms
    run.go:186: removing dir "A3/B1" failed - try 1/3: sleeping 1s: directory not empty
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- FAIL: TestDirMove (147.20s)
=== RUN   TestGetFsInfo
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:57:49 DEBUG : Config file has changed externally - reloading
--- PASS: TestGetFsInfo (0.45s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:57:50 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe': File to upload is small (34 bytes), uploading instead of streaming
2026/07/30 02:57:52 DEBUG : no_checksum_small_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:57:52 DEBUG : no_checksum_small_file_from_pipe: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:57:52 DEBUG : no_checksum_small_file_from_pipe: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:57:53 DEBUG : no_checksum_small_file_from_pipe: Trying to read object after upload: try again in 800ms (4/10)
2026/07/30 02:57:54 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/07/30 02:57:54 NOTICE: Linkbox root 'rclone-test-boviwoy3pefe': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/07/30 02:57:54 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/07/30 02:57:54 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe': Target remote doesn't support streaming uploads, creating temporary local FS to spool file
2026/07/30 02:57:56 DEBUG : no_checksum_big_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:57:57 DEBUG : no_checksum_big_file_from_pipe: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:57:57 DEBUG : no_checksum_big_file_from_pipe: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:57:58 DEBUG : no_checksum_big_file_from_pipe: Trying to read object after upload: try again in 800ms (4/10)
2026/07/30 02:57:59 DEBUG : no_checksum_big_file_from_pipe: Trying to read object after upload: try again in 1.6s (5/10)
2026/07/30 02:58:00 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/07/30 02:58:00 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 7s just to make sure
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=false
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:58:15 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe': File to upload is small (34 bytes), uploading instead of streaming
2026/07/30 02:58:17 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK
2026/07/30 02:58:17 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/07/30 02:58:17 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe': Target remote doesn't support streaming uploads, creating temporary local FS to spool file
2026/07/30 02:58:19 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:58:19 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/30 02:58:19 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:58:19 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/30 02:58:20 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:58:20 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/30 02:58:20 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:58:20 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/30 02:58:22 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/30 02:58:24 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/30 02:58:24 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK
2026/07/30 02:58:24 DEBUG : with_checksum_big_file_from_pipe: Size of src and dst objects identical
2026/07/30 02:58:26 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/30 02:58:28 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/30 02:58:30 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/30 02:58:31 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/30 02:58:32 DEBUG : pacer: Reducing sleep to 355.957031ms
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
2026/07/30 02:58:33 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/30 02:58:33 DEBUG : pacer: Reducing sleep to 200.225829ms
    fstest.go:292: Sleeping for 6s just to make sure
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=true
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:58:39 DEBUG : pacer: Reducing sleep to 200ms
2026/07/30 02:58:39 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe': File to upload is small (34 bytes), uploading instead of streaming
2026/07/30 02:58:41 DEBUG : ignore_checksum_small_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:58:42 DEBUG : ignore_checksum_small_file_from_pipe: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:58:42 DEBUG : ignore_checksum_small_file_from_pipe: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:58:43 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/07/30 02:58:43 DEBUG : ignore_checksum_small_file_from_pipe: Sizes identical
2026/07/30 02:58:43 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe': Target remote doesn't support streaming uploads, creating temporary local FS to spool file
2026/07/30 02:58:45 DEBUG : ignore_checksum_big_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:58:45 DEBUG : ignore_checksum_big_file_from_pipe: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:58:45 DEBUG : ignore_checksum_big_file_from_pipe: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:58:46 DEBUG : ignore_checksum_big_file_from_pipe: Trying to read object after upload: try again in 800ms (4/10)
2026/07/30 02:58:47 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/07/30 02:58:47 DEBUG : ignore_checksum_big_file_from_pipe: Sizes identical
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=true
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
2026/07/30 02:58:58 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe': File to upload is small (34 bytes), uploading instead of streaming
2026/07/30 02:59:00 DEBUG : ignore_checksum_small_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:59:00 DEBUG : ignore_checksum_small_file_from_pipe: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:59:01 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/07/30 02:59:01 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/07/30 02:59:01 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe': Target remote doesn't support streaming uploads, creating temporary local FS to spool file
2026/07/30 02:59:03 DEBUG : ignore_checksum_big_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:59:03 DEBUG : ignore_checksum_big_file_from_pipe: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:59:04 DEBUG : ignore_checksum_big_file_from_pipe: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:59:04 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/07/30 02:59:04 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestRcat (93.39s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (25.51s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (24.10s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (11.09s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (32.69s)
=== RUN   TestRcatMetadata
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
    operations_test.go:1563: Skipping as destination doesn't support user metadata
--- SKIP: TestRcatMetadata (0.40s)
=== RUN   TestRcatSize
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:59:25 DEBUG : potato1: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:59:26 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe': File to upload is small (60 bytes), uploading instead of streaming
2026/07/30 02:59:28 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:59:28 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:59:28 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:59:29 DEBUG : potato2: size = 60 OK
2026/07/30 02:59:29 DEBUG : potato2: Size of src and dst objects identical
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestRcatSize (16.70s)
=== RUN   TestRcatSizeMetadata
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
    operations_test.go:1644: Skipping as destination doesn't support user metadata
--- SKIP: TestRcatSizeMetadata (0.40s)
=== RUN   TestRcatSizeUploadHeaders
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
2026/07/30 02:59:42 DEBUG : potato1: Trying to read object after upload: try again in 100ms (1/10)
2026/07/30 02:59:43 DEBUG : potato1: Trying to read object after upload: try again in 200ms (2/10)
2026/07/30 02:59:43 DEBUG : potato1: Trying to read object after upload: try again in 400ms (3/10)
2026/07/30 02:59:44 DEBUG : potato1: Trying to read object after upload: try again in 800ms (4/10)
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 7s just to make sure
--- PASS: TestRcatSizeUploadHeaders (17.37s)
=== RUN   TestTouchDir
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
    operations_test.go:1735: Skipping test as remote does not support modtime
--- SKIP: TestTouchDir (0.42s)
=== RUN   TestMkdirMetadata
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
    operations_test.go:1773: Skipping test as remote does not support MkdirMetadata
--- SKIP: TestMkdirMetadata (0.40s)
=== RUN   TestMkdirModTime
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
    operations_test.go:1792: Skipping test as remote does not support DirSetModTime or MkdirMetadata
--- SKIP: TestMkdirModTime (0.40s)
=== RUN   TestCopyDirMetadata
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
    operations_test.go:1813: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata
--- SKIP: TestCopyDirMetadata (0.41s)
=== RUN   TestSetDirModTime
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
    operations_test.go:1849: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime
--- SKIP: TestSetDirModTime (0.40s)
=== RUN   TestDirsEqual
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
    operations_test.go:1893: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata
--- SKIP: TestDirsEqual (0.43s)
=== RUN   TestRemoveExisting
    run.go:198: Remote "Linkbox root 'rclone-test-boviwoy3pefe'", Local "Local file system at /tmp/rclone1634275649", Modify Window "876000h0m0s"
    operations_test.go:1962: Skipping as remote can't Move
--- SKIP: TestRemoveExisting (0.44s)
FAIL
2026/07/30 03:00:01 DEBUG : Linkbox root 'rclone-test-boviwoy3pefe': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestApplyTransforms|TestCaseInsensitiveMoveFileDryRun|TestCat|TestCheckSumDownload|TestCopyDirMetadata|TestCopyFileCompareDest|TestCopyURL|TestCopyURLDownloadHeaders|TestCount|TestDelete|TestDirMove|TestDirsEqual|TestGetFsInfo|TestHashSums|TestMaxDelete|TestMaxDeleteSize|TestMaxDeleteSizeLargeFile|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRcatSizeUploadHeaders|TestReadFile|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestSetDirModTime|TestTouchDir)$|^TestCheck$/^1$|^TestCheckSizeOnly$/^6$|^TestListJSON$/^(Default|DirsOnly|FilesOnly|HashTypes|Metadata|NoMimeType|NoModTime|Recurse|ShowHash)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$|^TestStatJSON$/^Root$'" - Finished ERROR in 19m59.176941467s (try 2/5): exit status 1: Failed [TestCheckSumDownload TestHashSums TestCat TestRmdirsNoLeaveRoot TestCopyURLDownloadHeaders TestMoveFile TestDirMove]