"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCheckSumDownload|TestDirMove|TestIndexChanged)$'" - Starting (try 4/5)
2026/10/05 07:39:22 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-pesofev8loce"
2026/10/05 07:39:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/05 07:39:22 DEBUG : Linkbox root 'rclone-test-pesofev8loce': Using cached web token
2026/10/05 07:39:23 DEBUG : Creating backend with remote "/tmp/rclone3335654596"
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Linkbox root 'rclone-test-pesofev8loce'", Local "Local file system at /tmp/rclone3335654596", Modify Window "876000h0m0s"
2026/10/05 07:39:23 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-pesofev8loce/data"
2026/10/05 07:39:23 DEBUG : Linkbox root 'rclone-test-pesofev8loce/data': Using cached web token
=== RUN   TestCheckSumDownload/subtest1
2026/10/05 07:39:36 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest2
2026/10/05 07:39:40 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest3
2026/10/05 07:39:44 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest4
2026/10/05 07:39:49 DEBUG : test.sum: Update: removing old file
2026/10/05 07:39:50 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:39:50 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/10/05 07:39:50 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:39:50 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/10/05 07:39:51 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:39:51 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/10/05 07:39:51 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:39:51 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/10/05 07:39:53 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:39:55 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:39:57 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:39:59 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:01 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:03 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-pesofev8loce': 1/10 (Update err in unmarshaling response: invalid character '<' looking for beginning of value)
2026/10/05 07:40:05 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:07 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:09 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:11 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:13 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:40:15 DEBUG : pacer: Reducing sleep to 1.5s
2026/10/05 07:40:17 DEBUG : pacer: Reducing sleep to 1.125s
2026/10/05 07:40:20 DEBUG : pacer: Reducing sleep to 843.75ms
2026/10/05 07:40:20 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/10/05 07:40:21 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/10/05 07:40:22 DEBUG : pacer: Reducing sleep to 355.957031ms
=== RUN   TestCheckSumDownload/subtest5
2026/10/05 07:40:23 DEBUG : pacer: Reducing sleep to 200ms
2026/10/05 07:40:23 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest6
2026/10/05 07:40:27 DEBUG : data/banana: Update: removing old file
2026/10/05 07:40:31 DEBUG : data/potato: Update: removing old file
2026/10/05 07:40:34 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (81.49s)
    --- PASS: TestCheckSumDownload/subtest1 (1.24s)
    --- PASS: TestCheckSumDownload/subtest2 (0.49s)
    --- PASS: TestCheckSumDownload/subtest3 (0.73s)
    --- PASS: TestCheckSumDownload/subtest4 (0.68s)
    --- PASS: TestCheckSumDownload/subtest5 (1.22s)
    --- PASS: TestCheckSumDownload/subtest6 (0.68s)
    --- PASS: TestCheckSumDownload/subtest7 (0.69s)
=== RUN   TestIndexChanged
    run.go:198: Remote "Linkbox root 'rclone-test-pesofev8loce'", Local "Local file system at /tmp/rclone3335654596", Modify Window "876000h0m0s"
2026/10/05 07:41:02 DEBUG : index.html: size = 12399 OK
2026/10/05 07:41:02 INFO  : index.html: Copied (new)
2026/10/05 07:41:02 DEBUG : other/index.html: size = 12058 OK
2026/10/05 07:41:02 INFO  : other/index.html: Copied (new)
2026/10/05 07:41:03 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/05 07:41:03 INFO  : sub/deep/index.html: Copied (new)
2026/10/05 07:41:03 DEBUG : sub/index.html: size = 12306 OK
2026/10/05 07:41:03 INFO  : sub/index.html: Copied (new)
2026/10/05 07:41:07 INFO  : Linkbox root 'rclone-test-pesofev8loce': Partial index: listing 3 directories and walking 0 changed directories
2026/10/05 07:41:07 DEBUG : sub/deep/index.html: Update: removing old file
2026/10/05 07:41:08 DEBUG : index.html: Unchanged skipping
2026/10/05 07:41:08 DEBUG : sub/index.html: Unchanged skipping
2026/10/05 07:41:09 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:41:09 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/10/05 07:41:10 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:41:10 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/10/05 07:41:10 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:41:10 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/10/05 07:41:11 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:41:11 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/10/05 07:41:12 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:41:14 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:41:18 DEBUG : pacer: Reducing sleep to 1.5s
2026/10/05 07:41:19 DEBUG : pacer: Reducing sleep to 1.125s
2026/10/05 07:41:19 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/05 07:41:19 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/05 07:41:20 DEBUG : pacer: Reducing sleep to 843.75ms
2026/10/05 07:41:21 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/10/05 07:41:22 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/10/05 07:41:23 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/10/05 07:41:23 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/10/05 07:41:25 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/10/05 07:41:25 DEBUG : pacer: Reducing sleep to 200ms
2026/10/05 07:41:27 INFO  : Linkbox root 'rclone-test-pesofev8loce': Partial index: listing 3 directories and walking 0 changed directories
2026/10/05 07:41:27 DEBUG : sub/index.html: Update: removing old file
2026/10/05 07:41:28 DEBUG : index.html: Unchanged skipping
2026/10/05 07:41:30 DEBUG : sub/new/index.html: Trying to read object after upload: try again in 100ms (1/10)
2026/10/05 07:41:30 DEBUG : sub/new/index.html: size = 12085 OK
2026/10/05 07:41:30 INFO  : sub/new/index.html: Copied (new)
2026/10/05 07:41:31 DEBUG : sub/index.html: size = 12554 OK
2026/10/05 07:41:31 INFO  : sub/index.html: Copied (replaced existing)
2026/10/05 07:41:34 INFO  : Linkbox root 'rclone-test-pesofev8loce': Partial index: listing 1 directories and walking 1 changed directories
2026/10/05 07:41:34 DEBUG : other/index.html: Update: removing old file
2026/10/05 07:41:35 DEBUG : index.html: Unchanged skipping
2026/10/05 07:41:38 DEBUG : other/index.html: size = 12597 OK
2026/10/05 07:41:38 INFO  : other/index.html: Copied (replaced existing)
2026/10/05 07:41:40 INFO  : Linkbox root 'rclone-test-pesofev8loce': Partial index: listing 1 directories and walking 0 changed directories
2026/10/05 07:41:41 DEBUG : other/index.html: Update: removing old file
2026/10/05 07:41:41 DEBUG : index.html: Unchanged skipping
2026/10/05 07:41:44 DEBUG : other/index.html: size = 12867 OK
2026/10/05 07:41:44 INFO  : other/index.html: Copied (replaced existing)
2026/10/05 07:41:47 INFO  : Linkbox root 'rclone-test-pesofev8loce': Nothing changed so nothing to index
2026/10/05 07:41:47 INFO  : Linkbox root 'rclone-test-pesofev8loce': Root changed so doing a full index
2026/10/05 07:41:48 DEBUG : sub/index.html: Update: removing old file
2026/10/05 07:41:48 DEBUG : index.html: Unchanged skipping
2026/10/05 07:41:48 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/05 07:41:48 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/05 07:41:49 DEBUG : other/index.html: Unchanged skipping
2026/10/05 07:41:52 DEBUG : sub/index.html: size = 12813 OK
2026/10/05 07:41:52 INFO  : sub/index.html: Copied (replaced existing)
2026/10/05 07:41:54 INFO  : Linkbox root 'rclone-test-pesofev8loce': Partial index would list 2 directories, more than 1, so doing a full index
2026/10/05 07:41:55 DEBUG : sub/index.html: Update: removing old file
2026/10/05 07:41:55 DEBUG : index.html: Unchanged skipping
2026/10/05 07:41:56 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/05 07:41:56 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/05 07:41:56 DEBUG : other/index.html: Unchanged skipping
2026/10/05 07:41:59 DEBUG : sub/index.html: size = 13071 OK
2026/10/05 07:41:59 INFO  : sub/index.html: Copied (replaced existing)
2026/10/05 07:42:02 INFO  : Linkbox root 'rclone-test-pesofev8loce': Partial index: listing 1 directories and walking 1 changed directories
2026/10/05 07:42:03 DEBUG : sub/deep/index.html: Update: removing old file
2026/10/05 07:42:03 DEBUG : index.html: Unchanged skipping
2026/10/05 07:42:03 DEBUG : sub/new/index.html: Unchanged skipping
2026/10/05 07:42:04 DEBUG : sub/index.html: Unchanged skipping
2026/10/05 07:42:06 DEBUG : sub/deep/index.html: size = 12604 OK
2026/10/05 07:42:06 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/05 07:42:11 INFO  : Linkbox root 'rclone-test-pesofev8loce': Partial index: listing 2 directories and walking 0 changed directories
2026/10/05 07:42:11 DEBUG : sub/index.html: Update: removing old file
2026/10/05 07:42:11 DEBUG : index.html: Unchanged skipping
2026/10/05 07:42:15 DEBUG : sub/index.html: size = 13331 OK
2026/10/05 07:42:15 INFO  : sub/index.html: Copied (replaced existing)
--- PASS: TestIndexChanged (123.17s)
=== RUN   TestDirMove
    run.go:198: Remote "Linkbox root 'rclone-test-pesofev8loce'", Local "Local file system at /tmp/rclone3335654596", Modify Window "876000h0m0s"
2026/10/05 07:43:04 INFO  : A1/B2: Making directory
2026/10/05 07:43:06 INFO  : A1/B1/C3: Making directory
2026/10/05 07:43:25 DEBUG : A2/B1/C1/four: size = 4 OK
2026/10/05 07:43:25 INFO  : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four
2026/10/05 07:43:25 DEBUG : A2/B1/C2/five: size = 4 OK
2026/10/05 07:43:25 INFO  : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five
2026/10/05 07:43:26 DEBUG : A2/one: size = 3 OK
2026/10/05 07:43:26 INFO  : A1/one: Copied (new) to: A2/one
2026/10/05 07:43:26 DEBUG : A2/B1/three: size = 5 OK
2026/10/05 07:43:26 INFO  : A1/B1/three: Copied (new) to: A2/B1/three
2026/10/05 07:43:26 DEBUG : A2/two: size = 3 OK
2026/10/05 07:43:26 INFO  : A1/two: Copied (new) to: A2/two
2026/10/05 07:43:27 INFO  : A1/B1/C1/four: Deleted
2026/10/05 07:43:27 INFO  : A1/B1/C2/five: Deleted
2026/10/05 07:43:28 INFO  : A1/one: Deleted
2026/10/05 07:43:28 INFO  : A1/B1/three: Deleted
2026/10/05 07:43:28 INFO  : A1/two: Deleted
2026/10/05 07:43:55 DEBUG : A3/B1/C2/five: size = 4 OK
2026/10/05 07:43:55 INFO  : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five
2026/10/05 07:43:55 DEBUG : A3/one: size = 3 OK
2026/10/05 07:43:55 INFO  : A2/one: Copied (new) to: A3/one
2026/10/05 07:43:55 DEBUG : A3/two: size = 3 OK
2026/10/05 07:43:55 INFO  : A2/two: Copied (new) to: A3/two
2026/10/05 07:43:56 DEBUG : A3/B1/three: size = 5 OK
2026/10/05 07:43:56 INFO  : A2/B1/three: Copied (new) to: A3/B1/three
2026/10/05 07:43:56 DEBUG : A3/B1/C1/four: size = 4 OK
2026/10/05 07:43:56 INFO  : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four
2026/10/05 07:43:56 INFO  : A2/B1/C2/five: Deleted
2026/10/05 07:43:57 INFO  : A2/one: Deleted
2026/10/05 07:43:57 INFO  : A2/two: Deleted
2026/10/05 07:43:58 INFO  : A2/B1/three: Deleted
2026/10/05 07:43:58 INFO  : A2/B1/C1/four: Deleted
2026/10/05 07:44:09 INFO  : Linkbox root 'rclone-test-pesofev8loce': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/10/05 07:44:09 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:44:09 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/10/05 07:44:10 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:44:10 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/10/05 07:44:10 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:44:10 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/10/05 07:44:11 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:44:11 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/10/05 07:44:12 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:44:14 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:44:16 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/10/05 07:44:18 DEBUG : pacer: Reducing sleep to 1.5s
2026/10/05 07:44:20 DEBUG : pacer: Reducing sleep to 1.125s
2026/10/05 07:44:22 DEBUG : pacer: Reducing sleep to 843.75ms
2026/10/05 07:44:23 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/10/05 07:44:24 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/10/05 07:44:26 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/10/05 07:44:26 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/10/05 07:44:27 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/10/05 07:44:28 DEBUG : pacer: Reducing sleep to 200ms
2026/10/05 07:44:39 DEBUG : A4/one: size = 3 OK
2026/10/05 07:44:39 INFO  : A3/one: Copied (new) to: A4/one
2026/10/05 07:44:40 DEBUG : A4/B1/C2/five: size = 4 OK
2026/10/05 07:44:40 INFO  : A3/B1/C2/five: Copied (new) to: A4/B1/C2/five
2026/10/05 07:44:40 DEBUG : A4/two: size = 3 OK
2026/10/05 07:44:40 INFO  : A3/two: Copied (new) to: A4/two
2026/10/05 07:44:40 DEBUG : A4/B1/three: size = 5 OK
2026/10/05 07:44:40 INFO  : A3/B1/three: Copied (new) to: A4/B1/three
2026/10/05 07:44:41 DEBUG : A4/B1/C1/four: size = 4 OK
2026/10/05 07:44:41 INFO  : A3/B1/C1/four: Copied (new) to: A4/B1/C1/four
2026/10/05 07:44:41 INFO  : A3/one: Deleted
2026/10/05 07:44:41 INFO  : A3/B1/C2/five: Deleted
2026/10/05 07:44:42 INFO  : A3/two: Deleted
2026/10/05 07:44:42 INFO  : A3/B1/three: Deleted
2026/10/05 07:44:42 INFO  : A3/B1/C1/four: Deleted
--- PASS: TestDirMove (142.88s)
PASS
2026/10/05 07:45:11 DEBUG : Linkbox root 'rclone-test-pesofev8loce': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCheckSumDownload|TestDirMove|TestIndexChanged)$'" - Finished OK in 5m49.854095491s (try 4/5)