"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^TestDirMove$'" - Starting (try 3/5)
2026/08/29 04:28:00 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-tehinen8zihe"
2026/08/29 04:28:00 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/08/29 04:28:00 DEBUG : Linkbox root 'rclone-test-tehinen8zihe': Using cached web token
2026/08/29 04:28:01 DEBUG : Creating backend with remote "/tmp/rclone1813824937"
=== RUN   TestDirMove
    run.go:198: Remote "Linkbox root 'rclone-test-tehinen8zihe'", Local "Local file system at /tmp/rclone1813824937", Modify Window "876000h0m0s"
2026/08/29 04:28:07 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:07 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/29 04:28:07 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:07 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/29 04:28:07 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:07 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/29 04:28:08 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:08 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/29 04:28:10 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:12 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:14 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:16 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:18 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:20 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:22 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:24 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:26 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:28 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:28:30 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/29 04:28:33 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/29 04:28:33 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/29 04:28:34 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/29 04:28:36 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/29 04:28:37 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/29 04:28:37 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/29 04:28:39 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/29 04:28:39 DEBUG : pacer: Reducing sleep to 200ms
2026/08/29 04:28:46 INFO  : A1/B2: Making directory
2026/08/29 04:28:47 INFO  : A1/B1/C3: Making directory
2026/08/29 04:29:02 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1825h7m18.529119234s different from this computer
2026/08/29 04:29:04 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:04 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/29 04:29:04 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:04 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/29 04:29:04 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:04 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/29 04:29:05 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:05 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/29 04:29:07 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:09 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:11 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:13 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:15 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:17 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:19 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:21 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:23 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:25 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:27 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:29 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:29:31 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/29 04:29:33 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/29 04:29:34 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/29 04:29:36 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/29 04:29:36 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/29 04:29:38 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/29 04:29:39 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/29 04:29:39 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/29 04:29:39 DEBUG : A2/B1/three: size = 5 OK
2026/08/29 04:29:39 INFO  : A1/B1/three: Copied (new) to: A2/B1/three
2026/08/29 04:29:39 DEBUG : pacer: Reducing sleep to 200ms
2026/08/29 04:29:39 DEBUG : A2/one: size = 3 OK
2026/08/29 04:29:39 INFO  : A1/one: Copied (new) to: A2/one
2026/08/29 04:29:40 DEBUG : A2/B1/C1/four: size = 4 OK
2026/08/29 04:29:40 INFO  : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four
2026/08/29 04:29:40 DEBUG : A2/B1/C2/five: size = 4 OK
2026/08/29 04:29:40 INFO  : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five
2026/08/29 04:29:41 DEBUG : A2/two: size = 3 OK
2026/08/29 04:29:41 INFO  : A1/two: Copied (new) to: A2/two
2026/08/29 04:29:41 INFO  : A1/B1/three: Deleted
2026/08/29 04:29:41 INFO  : A1/one: Deleted
2026/08/29 04:29:42 INFO  : A1/B1/C1/four: Deleted
2026/08/29 04:29:42 INFO  : A1/B1/C2/five: Deleted
2026/08/29 04:29:42 INFO  : A1/two: Deleted
2026/08/29 04:30:06 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:06 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/29 04:30:06 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:06 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/29 04:30:07 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:07 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/29 04:30:07 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:07 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/29 04:30:09 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:11 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:13 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:15 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:17 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:19 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:21 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:23 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:25 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:27 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:30:31 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/29 04:30:33 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/29 04:30:35 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/29 04:30:36 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/29 04:30:37 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/29 04:30:37 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/29 04:30:39 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/29 04:30:39 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/29 04:30:39 DEBUG : pacer: Reducing sleep to 200ms
2026/08/29 04:30:39 DEBUG : A3/B1/C2/five: size = 4 OK
2026/08/29 04:30:39 INFO  : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five
2026/08/29 04:30:40 DEBUG : A3/one: size = 3 OK
2026/08/29 04:30:40 INFO  : A2/one: Copied (new) to: A3/one
2026/08/29 04:30:40 DEBUG : A3/two: size = 3 OK
2026/08/29 04:30:40 INFO  : A2/two: Copied (new) to: A3/two
2026/08/29 04:30:40 DEBUG : A3/B1/three: size = 5 OK
2026/08/29 04:30:40 INFO  : A2/B1/three: Copied (new) to: A3/B1/three
2026/08/29 04:30:41 DEBUG : A3/B1/C1/four: size = 4 OK
2026/08/29 04:30:41 INFO  : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four
2026/08/29 04:30:41 INFO  : A2/B1/C2/five: Deleted
2026/08/29 04:30:41 INFO  : A2/one: Deleted
2026/08/29 04:30:42 INFO  : A2/two: Deleted
2026/08/29 04:30:42 INFO  : A2/B1/three: Deleted
2026/08/29 04:30:42 INFO  : A2/B1/C1/four: Deleted
2026/08/29 04:30:53 INFO  : Linkbox root 'rclone-test-tehinen8zihe': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/08/29 04:31:06 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:06 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/29 04:31:06 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:06 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/29 04:31:06 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:06 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/29 04:31:07 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:07 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/29 04:31:09 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:11 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:13 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:15 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:17 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:19 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:21 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:23 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:25 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:27 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:31 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:31:33 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/29 04:31:35 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/29 04:31:37 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/29 04:31:38 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/29 04:31:38 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/29 04:31:39 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/29 04:31:40 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/29 04:31:40 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/29 04:31:40 DEBUG : pacer: Reducing sleep to 200ms
2026/08/29 04:31:42 DEBUG : A4/B1/three: size = 5 OK
2026/08/29 04:31:42 INFO  : A3/B1/three: Copied (new) to: A4/B1/three
2026/08/29 04:31:42 DEBUG : A4/two: size = 3 OK
2026/08/29 04:31:42 INFO  : A3/two: Copied (new) to: A4/two
2026/08/29 04:31:42 DEBUG : A4/B1/C2/five: size = 4 OK
2026/08/29 04:31:42 INFO  : A3/B1/C2/five: Copied (new) to: A4/B1/C2/five
2026/08/29 04:31:43 DEBUG : A4/one: size = 3 OK
2026/08/29 04:31:43 INFO  : A3/one: Copied (new) to: A4/one
2026/08/29 04:31:43 INFO  : A3/B1/three: Deleted
2026/08/29 04:31:43 DEBUG : A4/B1/C1/four: size = 4 OK
2026/08/29 04:31:43 INFO  : A3/B1/C1/four: Copied (new) to: A4/B1/C1/four
2026/08/29 04:31:44 INFO  : A3/two: Deleted
2026/08/29 04:31:44 INFO  : A3/B1/C2/five: Deleted
2026/08/29 04:31:44 INFO  : A3/one: Deleted
2026/08/29 04:31:45 INFO  : A3/B1/C1/four: Deleted
2026/08/29 04:32:06 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:06 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/29 04:32:06 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:06 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/29 04:32:07 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:07 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/29 04:32:08 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:08 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/29 04:32:09 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:11 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:13 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:15 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:17 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:19 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
    run.go:186: removing dir "A4/B1/C2" failed - try 1/3: sleeping 1s: getting files failed: invalid character '<' looking for beginning of value
2026/08/29 04:32:21 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:23 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:25 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:27 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:29 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:31 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/29 04:32:33 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/29 04:32:36 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/29 04:32:37 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/29 04:32:39 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/29 04:32:39 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/29 04:32:41 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/29 04:32:41 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/29 04:32:43 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/29 04:32:43 DEBUG : pacer: Reducing sleep to 200ms
--- PASS: TestDirMove (281.63s)
PASS
2026/08/29 04:32:43 DEBUG : Linkbox root 'rclone-test-tehinen8zihe': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^TestDirMove$'" - Finished OK in 4m44.074186811s (try 3/5)