"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestDirMove|TestRmdirsNoLeaveRoot)$'" - Starting (try 2/5)
2025/10/16 01:56:39 DEBUG : Creating backend with remote "TestPikPak:rclone-test-dujepez5wene"
2025/10/16 01:56:39 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/16 01:56:41 DEBUG : Creating backend with remote "/tmp/rclone1733145081"
=== RUN   TestRmdirsNoLeaveRoot
    run.go:180: Remote "PikPak root 'rclone-test-dujepez5wene'", Local "Local file system at /tmp/rclone1733145081", Modify Window "876000h0m0s"
2025/10/16 01:56:46 DEBUG : A1/B1/C1/one: calculating gcid: failed to unwrap object from src: A1/B1/C1/one
2025/10/16 01:56:46 DEBUG : A1/B1/C1/one: gcid = a02aa727cf2e8c5e6f07a382910c4028d65a053a
2025/10/16 01:56:46 INFO  : A2: Making directory
2025/10/16 01:56:47 INFO  : A1/B2: Making directory
2025/10/16 01:56:48 INFO  : A1/B2/C2: Making directory
2025/10/16 01:56:50 INFO  : A1/B1/C3: Making directory
2025/10/16 01:56:50 INFO  : A3: Making directory
2025/10/16 01:56:51 INFO  : A3/B3: Making directory
2025/10/16 01:56:52 INFO  : A3/B3/C4: Making directory
2025/10/16 01:56:53 DEBUG : A1/two: calculating gcid: failed to unwrap object from src: A1/two
2025/10/16 01:56:53 DEBUG : A1/two: gcid = 8be34f24d29e7b61ee00e4d5aadae5cbc713d120
2025/10/16 01:56:55 DEBUG : removing 1 level 3 directories
2025/10/16 01:56:55 INFO  : A3/B3/C4: Removing directory
2025/10/16 01:57:02 DEBUG : removing 2 level 3 directories
2025/10/16 01:57:02 INFO  : A1/B2/C2: Removing directory
2025/10/16 01:57:02 INFO  : A1/B1/C3: Removing directory
2025/10/16 01:57:04 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/10/16 01:57:04 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/10/16 01:57:04 DEBUG : pacer: Reducing sleep to 150ms
2025/10/16 01:57:05 DEBUG : pacer: Reducing sleep to 112.5ms
2025/10/16 01:57:05 DEBUG : removing 2 level 2 directories
2025/10/16 01:57:05 INFO  : A3/B3: Removing directory
2025/10/16 01:57:05 INFO  : A1/B2: Removing directory
2025/10/16 01:57:05 DEBUG : pacer: Reducing sleep to 100ms
2025/10/16 01:57:05 DEBUG : A3/B3: Rmdir: contains trashed file: "C4"
2025/10/16 01:57:07 DEBUG : A1/B2: Rmdir: contains trashed file: "C2"
2025/10/16 01:57:08 DEBUG : removing 2 level 1 directories
2025/10/16 01:57:08 INFO  : A3: Removing directory
2025/10/16 01:57:08 INFO  : A2: Removing directory
2025/10/16 01:57:08 DEBUG : A3: Rmdir: contains trashed file: "B3"
2025/10/16 01:57:17 DEBUG : removing 1 level 3 directories
2025/10/16 01:57:17 INFO  : A1/B1/C1: Removing directory
2025/10/16 01:57:18 DEBUG : A1/B1/C1: Rmdir: contains trashed file: "one"
2025/10/16 01:57:19 DEBUG : removing 1 level 2 directories
2025/10/16 01:57:19 INFO  : A1/B1: Removing directory
2025/10/16 01:57:19 DEBUG : A1/B1: Rmdir: contains trashed file: "C3"
2025/10/16 01:57:19 DEBUG : A1/B1: Rmdir: contains trashed file: "C1"
2025/10/16 01:57:21 DEBUG : removing 1 level 1 directories
2025/10/16 01:57:21 INFO  : A1: Removing directory
2025/10/16 01:57:22 DEBUG : A1: Rmdir: contains trashed file: "B2"
2025/10/16 01:57:22 DEBUG : A1: Rmdir: contains trashed file: "B1"
2025/10/16 01:57:22 DEBUG : A1: Rmdir: contains trashed file: "two"
2025/10/16 01:57:23 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/10/16 01:57:23 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/10/16 01:57:24 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/10/16 01:57:24 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/10/16 01:57:24 DEBUG : pacer: Reducing sleep to 300ms
2025/10/16 01:57:24 DEBUG : removing 1 level 0 directories
2025/10/16 01:57:24 INFO  : PikPak root 'rclone-test-dujepez5wene': Removing directory
2025/10/16 01:57:27 DEBUG : pacer: Reducing sleep to 225ms
2025/10/16 01:57:27 DEBUG : Rmdir: contains trashed file: "A3"
2025/10/16 01:57:27 DEBUG : Rmdir: contains trashed file: "A2"
2025/10/16 01:57:27 DEBUG : Rmdir: contains trashed file: "A1"
2025/10/16 01:57:28 DEBUG : pacer: Reducing sleep to 168.75ms
2025/10/16 01:57:28 DEBUG : pacer: Reducing sleep to 126.5625ms
2025/10/16 01:57:29 DEBUG : pacer: Reducing sleep to 100ms
2025/10/16 01:57:29 ERROR : error listing: directory not found
2025/10/16 01:57:29 ERROR : error listing: directory not found
--- PASS: TestRmdirsNoLeaveRoot (47.77s)
=== RUN   TestDirMove
    run.go:180: Remote "PikPak root 'rclone-test-dujepez5wene'", Local "Local file system at /tmp/rclone1733145081", Modify Window "876000h0m0s"
2025/10/16 01:57:32 DEBUG : A1/one: calculating gcid: failed to unwrap object from src: A1/one
2025/10/16 01:57:32 DEBUG : A1/one: gcid = 74445cd742af268fd22866da0d12758951d638f7
2025/10/16 01:57:35 DEBUG : A1/two: calculating gcid: failed to unwrap object from src: A1/two
2025/10/16 01:57:35 DEBUG : A1/two: gcid = a41bee51488264c01b96de959a9142af1a75205e
2025/10/16 01:57:37 DEBUG : A1/B1/three: calculating gcid: failed to unwrap object from src: A1/B1/three
2025/10/16 01:57:37 DEBUG : A1/B1/three: gcid = aa5f498bee9d8e3a091610f6b4456e1a09763c27
2025/10/16 01:57:41 DEBUG : A1/B1/C1/four: calculating gcid: failed to unwrap object from src: A1/B1/C1/four
2025/10/16 01:57:41 DEBUG : A1/B1/C1/four: gcid = 8b8f52fdefe3ab828c814dda9a27cc4d9355c89b
2025/10/16 01:57:42 DEBUG : A1/B1/C2/five: calculating gcid: failed to unwrap object from src: A1/B1/C2/five
2025/10/16 01:57:42 DEBUG : A1/B1/C2/five: gcid = c5a78b0666272ddddef4fa491d9527439267a27a
2025/10/16 01:57:43 INFO  : A1/B2: Making directory
2025/10/16 01:57:44 INFO  : A1/B1/C3: Making directory
2025/10/16 01:58:00 DEBUG : pacer: low level retry 1/10 (error move (move) is still in PHASE_TYPE_RUNNING)
2025/10/16 01:58:00 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/10/16 01:58:00 DEBUG : pacer: Reducing sleep to 150ms
2025/10/16 01:58:00 DEBUG : pacer: Reducing sleep to 112.5ms
2025/10/16 01:58:00 DEBUG : pacer: Reducing sleep to 100ms
2025/10/16 01:58:00 INFO  : A2/two: Moved (server-side) to: A3/two
2025/10/16 01:58:00 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2025/10/16 01:58:01 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2025/10/16 01:58:01 INFO  : A2/one: Moved (server-side) to: A3/one
2025/10/16 01:58:01 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2025/10/16 01:58:09 DEBUG : A2/B1: Rmdir: contains trashed file: "C3"
2025/10/16 01:58:09 DEBUG : A2/B1: Rmdir: contains trashed file: "C2"
2025/10/16 01:58:09 DEBUG : A2/B1: Rmdir: contains trashed file: "C1"
2025/10/16 01:58:11 DEBUG : A2: Rmdir: contains trashed file: "B2"
2025/10/16 01:58:11 DEBUG : A2: Rmdir: contains trashed file: "B1"
2025/10/16 01:58:14 INFO  : PikPak root 'rclone-test-dujepez5wene': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2025/10/16 01:58:22 INFO  : A3/one: Moved (server-side) to: A4/one
2025/10/16 01:58:22 INFO  : A3/two: Moved (server-side) to: A4/two
2025/10/16 01:58:22 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2025/10/16 01:58:22 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2025/10/16 01:58:24 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2025/10/16 01:58:31 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/10/16 01:58:31 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/10/16 01:58:31 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/10/16 01:58:31 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/10/16 01:58:31 DEBUG : pacer: Reducing sleep to 300ms
2025/10/16 01:58:32 DEBUG : pacer: Reducing sleep to 225ms
2025/10/16 01:58:32 DEBUG : A3/B1: Rmdir: contains trashed file: "C3"
2025/10/16 01:58:32 DEBUG : A3/B1: Rmdir: contains trashed file: "C2"
2025/10/16 01:58:32 DEBUG : A3/B1: Rmdir: contains trashed file: "C1"
2025/10/16 01:58:32 DEBUG : pacer: Reducing sleep to 168.75ms
2025/10/16 01:58:33 DEBUG : pacer: Reducing sleep to 126.5625ms
2025/10/16 01:58:33 DEBUG : pacer: Reducing sleep to 100ms
2025/10/16 01:58:33 DEBUG : A3: Rmdir: contains trashed file: "B2"
2025/10/16 01:58:33 DEBUG : A3: Rmdir: contains trashed file: "B1"
2025/10/16 01:58:42 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/10/16 01:58:42 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/10/16 01:58:43 DEBUG : pacer: Reducing sleep to 150ms
2025/10/16 01:58:43 DEBUG : pacer: Reducing sleep to 112.5ms
2025/10/16 01:58:44 DEBUG : pacer: Reducing sleep to 100ms
2025/10/16 01:58:48 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/10/16 01:58:48 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/10/16 01:58:48 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/10/16 01:58:48 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/10/16 01:58:48 DEBUG : pacer: Reducing sleep to 300ms
2025/10/16 01:58:49 DEBUG : pacer: Reducing sleep to 225ms
2025/10/16 01:58:49 DEBUG : pacer: Reducing sleep to 168.75ms
2025/10/16 01:58:49 DEBUG : pacer: Reducing sleep to 126.5625ms
2025/10/16 01:58:49 DEBUG : pacer: Reducing sleep to 100ms
2025/10/16 01:58:56 DEBUG : A4/B1/C2: Rmdir: contains trashed file: "five"
2025/10/16 01:58:58 DEBUG : A4/B1/C1: Rmdir: contains trashed file: "four"
2025/10/16 01:59:00 DEBUG : A4/B1: Rmdir: contains trashed file: "C3"
2025/10/16 01:59:00 DEBUG : A4/B1: Rmdir: contains trashed file: "C2"
2025/10/16 01:59:00 DEBUG : A4/B1: Rmdir: contains trashed file: "C1"
2025/10/16 01:59:00 DEBUG : A4/B1: Rmdir: contains trashed file: "three"
2025/10/16 01:59:01 DEBUG : A4: Rmdir: contains trashed file: "B2"
2025/10/16 01:59:01 DEBUG : A4: Rmdir: contains trashed file: "B1"
2025/10/16 01:59:01 DEBUG : A4: Rmdir: contains trashed file: "two"
2025/10/16 01:59:01 DEBUG : A4: Rmdir: contains trashed file: "one"
--- PASS: TestDirMove (93.36s)
PASS
2025/10/16 01:59:02 DEBUG : PikPak root 'rclone-test-dujepez5wene': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestDirMove|TestRmdirsNoLeaveRoot)$'" - Finished OK in 2m24.343832195s (try 2/5)