"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^TestDirMove$'" - Starting (try 4/5)
2026/08/16 02:50:32 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-yedeyad8rumi"
2026/08/16 02:50:32 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/08/16 02:50:32 DEBUG : Linkbox root 'rclone-test-yedeyad8rumi': Using cached web token
2026/08/16 02:50:33 DEBUG : Creating backend with remote "/tmp/rclone3323013154"
=== RUN   TestDirMove
    run.go:198: Remote "Linkbox root 'rclone-test-yedeyad8rumi'", Local "Local file system at /tmp/rclone3323013154", Modify Window "876000h0m0s"
2026/08/16 02:50:51 INFO  : A1/B2: Making directory
2026/08/16 02:50:52 INFO  : A1/B1/C3: Making directory
2026/08/16 02:51:00 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:00 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/16 02:51:00 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:00 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/16 02:51:00 DEBUG : pacer: Reducing sleep to 600ms
2026/08/16 02:51:02 DEBUG : pacer: Reducing sleep to 450ms
2026/08/16 02:51:03 DEBUG : pacer: Reducing sleep to 337.5ms
2026/08/16 02:51:04 DEBUG : pacer: Reducing sleep to 253.125ms
2026/08/16 02:51:04 DEBUG : pacer: Reducing sleep to 200ms
2026/08/16 02:51:08 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1557h19m36.748589561s different from this computer
2026/08/16 02:51:13 DEBUG : A2/one: size = 3 OK
2026/08/16 02:51:13 INFO  : A1/one: Copied (new) to: A2/one
2026/08/16 02:51:13 DEBUG : A2/two: size = 3 OK
2026/08/16 02:51:13 INFO  : A1/two: Copied (new) to: A2/two
2026/08/16 02:51:13 DEBUG : A2/B1/three: size = 5 OK
2026/08/16 02:51:13 INFO  : A1/B1/three: Copied (new) to: A2/B1/three
2026/08/16 02:51:14 DEBUG : A2/B1/C1/four: size = 4 OK
2026/08/16 02:51:14 INFO  : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four
2026/08/16 02:51:14 DEBUG : A2/B1/C2/five: size = 4 OK
2026/08/16 02:51:14 INFO  : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five
2026/08/16 02:51:14 INFO  : A1/one: Deleted
2026/08/16 02:51:15 INFO  : A1/two: Deleted
2026/08/16 02:51:15 INFO  : A1/B1/three: Deleted
2026/08/16 02:51:16 INFO  : A1/B1/C1/four: Deleted
2026/08/16 02:51:16 INFO  : A1/B1/C2/five: Deleted
2026/08/16 02:51:42 DEBUG : A3/two: size = 3 OK
2026/08/16 02:51:42 INFO  : A2/two: Copied (new) to: A3/two
2026/08/16 02:51:43 DEBUG : A3/B1/three: size = 5 OK
2026/08/16 02:51:43 INFO  : A2/B1/three: Copied (new) to: A3/B1/three
2026/08/16 02:51:43 DEBUG : A3/one: size = 3 OK
2026/08/16 02:51:43 INFO  : A2/one: Copied (new) to: A3/one
2026/08/16 02:51:43 DEBUG : A3/B1/C2/five: size = 4 OK
2026/08/16 02:51:43 INFO  : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five
2026/08/16 02:51:43 DEBUG : A3/B1/C1/four: size = 4 OK
2026/08/16 02:51:43 INFO  : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four
2026/08/16 02:51:44 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:44 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/16 02:51:44 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:44 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/16 02:51:44 DEBUG : pacer: Reducing sleep to 600ms
2026/08/16 02:51:44 INFO  : A2/two: Deleted
2026/08/16 02:51:44 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:44 DEBUG : pacer: Rate limited, increasing sleep to 1.2s
2026/08/16 02:51:44 DEBUG : pacer: Reducing sleep to 900ms
2026/08/16 02:51:44 INFO  : A2/B1/three: Deleted
2026/08/16 02:51:45 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:45 DEBUG : pacer: Rate limited, increasing sleep to 1.8s
2026/08/16 02:51:46 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:46 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/16 02:51:47 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:49 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:51 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:53 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:55 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:57 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:51:59 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:52:03 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/16 02:52:03 INFO  : A2/one: Deleted
2026/08/16 02:52:05 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/16 02:52:05 INFO  : A2/B1/C2/five: Deleted
2026/08/16 02:52:06 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/16 02:52:06 INFO  : A2/B1/C1/four: Deleted
2026/08/16 02:52:06 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/16 02:52:08 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/16 02:52:09 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/16 02:52:10 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/16 02:52:10 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/16 02:52:12 DEBUG : pacer: Reducing sleep to 200ms
2026/08/16 02:52:18 INFO  : Linkbox root 'rclone-test-yedeyad8rumi': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/08/16 02:52:34 DEBUG : A4/B1/three: size = 5 OK
2026/08/16 02:52:34 INFO  : A3/B1/three: Copied (new) to: A4/B1/three
2026/08/16 02:52:34 DEBUG : A4/B1/C1/four: size = 4 OK
2026/08/16 02:52:34 INFO  : A3/B1/C1/four: Copied (new) to: A4/B1/C1/four
2026/08/16 02:52:35 DEBUG : A4/B1/C2/five: size = 4 OK
2026/08/16 02:52:35 INFO  : A3/B1/C2/five: Copied (new) to: A4/B1/C2/five
2026/08/16 02:52:35 DEBUG : A4/one: size = 3 OK
2026/08/16 02:52:35 INFO  : A3/one: Copied (new) to: A4/one
2026/08/16 02:52:35 DEBUG : A4/two: size = 3 OK
2026/08/16 02:52:35 INFO  : A3/two: Copied (new) to: A4/two
2026/08/16 02:52:35 INFO  : A3/B1/three: Deleted
2026/08/16 02:52:36 INFO  : A3/B1/C1/four: Deleted
2026/08/16 02:52:36 INFO  : A3/B1/C2/five: Deleted
2026/08/16 02:52:37 INFO  : A3/one: Deleted
2026/08/16 02:52:37 INFO  : A3/two: Deleted
2026/08/16 02:52:58 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:52:58 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/16 02:52:59 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:52:59 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/16 02:52:59 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:52:59 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/16 02:53:00 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/16 02:53:00 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/16 02:53:02 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/16 02:53:05 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/16 02:53:05 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/16 02:53:07 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/16 02:53:08 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/16 02:53:09 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/16 02:53:10 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/16 02:53:11 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/16 02:53:11 DEBUG : pacer: Reducing sleep to 200ms
--- PASS: TestDirMove (158.23s)
PASS
2026/08/16 02:53:11 DEBUG : Linkbox root 'rclone-test-yedeyad8rumi': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^TestDirMove$'" - Finished OK in 2m40.8243033s (try 4/5)