"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestDirMove$'" - Starting (try 2/5)
2026/05/22 03:41:13 DEBUG : Creating backend with remote "TestPikPak:rclone-test-nekavet4zuyu"
2026/05/22 03:41:13 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/22 03:41:15 DEBUG : Creating backend with remote "/tmp/rclone437368447"
=== RUN   TestDirMove
    run.go:198: Remote "PikPak root 'rclone-test-nekavet4zuyu'", Local "Local file system at /tmp/rclone437368447", Modify Window "876000h0m0s"
2026/05/22 03:41:17 DEBUG : A1/one: calculating gcid: failed to unwrap object from src: A1/one
2026/05/22 03:41:17 DEBUG : A1/one: gcid = 74445cd742af268fd22866da0d12758951d638f7
2026/05/22 03:41:17 DEBUG : A1/two: calculating gcid: failed to unwrap object from src: A1/two
2026/05/22 03:41:17 DEBUG : A1/two: gcid = a41bee51488264c01b96de959a9142af1a75205e
2026/05/22 03:41:18 DEBUG : A1/B1/three: calculating gcid: failed to unwrap object from src: A1/B1/three
2026/05/22 03:41:18 DEBUG : A1/B1/three: gcid = aa5f498bee9d8e3a091610f6b4456e1a09763c27
2026/05/22 03:41:20 DEBUG : A1/B1/C1/four: calculating gcid: failed to unwrap object from src: A1/B1/C1/four
2026/05/22 03:41:20 DEBUG : A1/B1/C1/four: gcid = 8b8f52fdefe3ab828c814dda9a27cc4d9355c89b
2026/05/22 03:41:21 DEBUG : A1/B1/C2/five: calculating gcid: failed to unwrap object from src: A1/B1/C2/five
2026/05/22 03:41:21 DEBUG : A1/B1/C2/five: gcid = c5a78b0666272ddddef4fa491d9527439267a27a
2026/05/22 03:41:21 INFO  : A1/B2: Making directory
2026/05/22 03:41:22 INFO  : A1/B1/C3: Making directory
2026/05/22 03:41:33 INFO  : A2/one: Moved (server-side) to: A3/one
2026/05/22 03:41:33 INFO  : A2/two: Moved (server-side) to: A3/two
2026/05/22 03:41:33 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2026/05/22 03:41:33 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2026/05/22 03:41:34 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2026/05/22 03:41:39 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2026/05/22 03:41:39 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2026/05/22 03:41:40 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2026/05/22 03:41:40 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/05/22 03:41:40 DEBUG : pacer: low level retry 3/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2026/05/22 03:41:40 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/05/22 03:41:40 DEBUG : pacer: Reducing sleep to 600ms
2026/05/22 03:41:42 DEBUG : pacer: Reducing sleep to 450ms
2026/05/22 03:41:42 DEBUG : A2/B1: Rmdir: contains trashed file: "C3"
2026/05/22 03:41:42 DEBUG : A2/B1: Rmdir: contains trashed file: "C2"
2026/05/22 03:41:42 DEBUG : A2/B1: Rmdir: contains trashed file: "C1"
2026/05/22 03:41:42 DEBUG : pacer: Reducing sleep to 337.5ms
2026/05/22 03:41:43 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2026/05/22 03:41:43 DEBUG : pacer: Rate limited, increasing sleep to 675ms
2026/05/22 03:41:43 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2026/05/22 03:41:43 DEBUG : pacer: Rate limited, increasing sleep to 1.35s
2026/05/22 03:41:44 DEBUG : pacer: Reducing sleep to 1.0125s
2026/05/22 03:41:46 DEBUG : pacer: Reducing sleep to 759.375ms
2026/05/22 03:41:46 DEBUG : A2: Rmdir: contains trashed file: "B2"
2026/05/22 03:41:46 DEBUG : A2: Rmdir: contains trashed file: "B1"
2026/05/22 03:41:46 DEBUG : pacer: Reducing sleep to 569.53125ms
2026/05/22 03:41:47 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2026/05/22 03:41:47 DEBUG : pacer: Rate limited, increasing sleep to 1.1390625s
2026/05/22 03:41:48 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2026/05/22 03:41:48 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/05/22 03:41:49 DEBUG : pacer: Reducing sleep to 1.5s
2026/05/22 03:41:51 DEBUG : pacer: Reducing sleep to 1.125s
2026/05/22 03:41:52 DEBUG : pacer: Reducing sleep to 843.75ms
2026/05/22 03:41:53 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/05/22 03:41:54 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/05/22 03:41:55 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/05/22 03:41:55 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/05/22 03:41:56 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/05/22 03:41:56 INFO  : PikPak root 'rclone-test-nekavet4zuyu': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/05/22 03:41:56 DEBUG : pacer: Reducing sleep to 150.169371ms
2026/05/22 03:41:56 DEBUG : pacer: Reducing sleep to 112.627028ms
2026/05/22 03:41:56 DEBUG : pacer: Reducing sleep to 100ms
2026/05/22 03:42:03 INFO  : A3/one: Moved (server-side) to: A4/one
2026/05/22 03:42:03 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2026/05/22 03:42:03 INFO  : A3/two: Moved (server-side) to: A4/two
2026/05/22 03:42:03 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2026/05/22 03:42:03 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2026/05/22 03:42:09 DEBUG : A3/B1: Rmdir: contains trashed file: "C3"
2026/05/22 03:42:09 DEBUG : A3/B1: Rmdir: contains trashed file: "C2"
2026/05/22 03:42:09 DEBUG : A3/B1: Rmdir: contains trashed file: "C1"
2026/05/22 03:42:10 DEBUG : A3: Rmdir: contains trashed file: "B2"
2026/05/22 03:42:10 DEBUG : A3: Rmdir: contains trashed file: "B1"
2026/05/22 03:42:23 DEBUG : A4/B1/C2: Rmdir: contains trashed file: "five"
2026/05/22 03:42:24 DEBUG : A4/B1/C1: Rmdir: contains trashed file: "four"
2026/05/22 03:42:25 DEBUG : A4/B1: Rmdir: contains trashed file: "C3"
2026/05/22 03:42:25 DEBUG : A4/B1: Rmdir: contains trashed file: "C2"
2026/05/22 03:42:25 DEBUG : A4/B1: Rmdir: contains trashed file: "C1"
2026/05/22 03:42:25 DEBUG : A4/B1: Rmdir: contains trashed file: "three"
2026/05/22 03:42:27 DEBUG : A4: Rmdir: contains trashed file: "B2"
2026/05/22 03:42:27 DEBUG : A4: Rmdir: contains trashed file: "B1"
2026/05/22 03:42:27 DEBUG : A4: Rmdir: contains trashed file: "two"
2026/05/22 03:42:27 DEBUG : A4: Rmdir: contains trashed file: "one"
--- PASS: TestDirMove (73.19s)
PASS
2026/05/22 03:42:28 DEBUG : PikPak root 'rclone-test-nekavet4zuyu': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestDirMove$'" - Finished OK in 1m15.706390186s (try 2/5)