"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestDirMove$'" - Starting (try 2/5)
2025/07/12 01:26:50 DEBUG : Creating backend with remote "TestPikPak:rclone-test-mijaxet9rebu"
2025/07/12 01:26:50 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/12 01:26:52 DEBUG : Creating backend with remote "/tmp/rclone3968361452"
=== RUN   TestDirMove
    run.go:180: Remote "PikPak root 'rclone-test-mijaxet9rebu'", Local "Local file system at /tmp/rclone3968361452", Modify Window "876000h0m0s"
2025/07/12 01:26:54 DEBUG : A1/one: calculating gcid: failed to unwrap object from src: A1/one
2025/07/12 01:26:54 DEBUG : A1/one: gcid = 74445cd742af268fd22866da0d12758951d638f7
2025/07/12 01:26:54 DEBUG : A1/two: calculating gcid: failed to unwrap object from src: A1/two
2025/07/12 01:26:54 DEBUG : A1/two: gcid = a41bee51488264c01b96de959a9142af1a75205e
2025/07/12 01:26:56 DEBUG : A1/B1/three: calculating gcid: failed to unwrap object from src: A1/B1/three
2025/07/12 01:26:56 DEBUG : A1/B1/three: gcid = aa5f498bee9d8e3a091610f6b4456e1a09763c27
2025/07/12 01:26:58 DEBUG : A1/B1/C1/four: calculating gcid: failed to unwrap object from src: A1/B1/C1/four
2025/07/12 01:26:58 DEBUG : A1/B1/C1/four: gcid = 8b8f52fdefe3ab828c814dda9a27cc4d9355c89b
2025/07/12 01:27:00 DEBUG : A1/B1/C2/five: calculating gcid: failed to unwrap object from src: A1/B1/C2/five
2025/07/12 01:27:00 DEBUG : A1/B1/C2/five: gcid = c5a78b0666272ddddef4fa491d9527439267a27a
2025/07/12 01:27:00 INFO  : A1/B2: Making directory
2025/07/12 01:27:01 INFO  : A1/B1/C3: Making directory
2025/07/12 01:27:13 INFO  : A2/one: Moved (server-side) to: A3/one
2025/07/12 01:27:14 INFO  : A2/two: Moved (server-side) to: A3/two
2025/07/12 01:27:14 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2025/07/12 01:27:14 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2025/07/12 01:27:14 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2025/07/12 01:27:20 DEBUG : A2/B1: Rmdir: contains trashed file: "C3"
2025/07/12 01:27:20 DEBUG : A2/B1: Rmdir: contains trashed file: "C2"
2025/07/12 01:27:20 DEBUG : A2/B1: Rmdir: contains trashed file: "C1"
2025/07/12 01:27:22 DEBUG : A2: Rmdir: contains trashed file: "B2"
2025/07/12 01:27:22 DEBUG : A2: Rmdir: contains trashed file: "B1"
2025/07/12 01:27:24 INFO  : PikPak root 'rclone-test-mijaxet9rebu': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2025/07/12 01:27:32 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2025/07/12 01:27:32 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2025/07/12 01:27:32 INFO  : A3/one: Moved (server-side) to: A4/one
2025/07/12 01:27:32 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2025/07/12 01:27:32 INFO  : A3/two: Moved (server-side) to: A4/two
2025/07/12 01:27:38 DEBUG : A3/B1: Rmdir: contains trashed file: "C3"
2025/07/12 01:27:38 DEBUG : A3/B1: Rmdir: contains trashed file: "C2"
2025/07/12 01:27:38 DEBUG : A3/B1: Rmdir: contains trashed file: "C1"
2025/07/12 01:27:40 DEBUG : A3: Rmdir: contains trashed file: "B2"
2025/07/12 01:27:40 DEBUG : A3: Rmdir: contains trashed file: "B1"
2025/07/12 01:27:51 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/07/12 01:27:51 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/07/12 01:27:51 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/07/12 01:27:51 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/07/12 01:27:52 DEBUG : pacer: low level retry 3/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/07/12 01:27:52 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2025/07/12 01:27:52 DEBUG : pacer: low level retry 4/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/07/12 01:27:52 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2025/07/12 01:27:53 DEBUG : pacer: low level retry 5/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/07/12 01:27:53 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/07/12 01:27:54 DEBUG : pacer: low level retry 6/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/07/12 01:27:56 DEBUG : pacer: low level retry 7/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/07/12 01:27:58 DEBUG : pacer: low level retry 8/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/07/12 01:28:00 DEBUG : pacer: low level retry 9/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/07/12 01:28:02 DEBUG : pacer: low level retry 10/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
    run.go:125: removing dir "A4/B2" failed - try 1/3: delete object failed: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOUwEiVAwV-FlnMZ8Ih2Ng67o2", Name:"trash", Type:"trash", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc000899580), FileID:"", FileName:"", FileSize:"0", Message:"Pending", CreatedTime:api.Time{wall:0x20207cc0, ext:63887880470, loc:(*time.Location)(0xc0004295e0)}, UpdatedTime:api.Time{wall:0x20207cc0, ext:63887880470, loc:(*time.Location)(0xc0004295e0)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""}
2025/07/12 01:28:05 DEBUG : pacer: Reducing sleep to 1.5s
2025/07/12 01:28:06 DEBUG : pacer: Reducing sleep to 1.125s
2025/07/12 01:28:08 DEBUG : pacer: Reducing sleep to 843.75ms
2025/07/12 01:28:09 DEBUG : pacer: Reducing sleep to 632.8125ms
2025/07/12 01:28:10 DEBUG : pacer: Reducing sleep to 474.609375ms
2025/07/12 01:28:11 DEBUG : pacer: Reducing sleep to 355.957031ms
2025/07/12 01:28:11 DEBUG : pacer: Reducing sleep to 266.967773ms
2025/07/12 01:28:11 DEBUG : A4/B1/C2: Rmdir: contains trashed file: "five"
2025/07/12 01:28:12 DEBUG : pacer: Reducing sleep to 200.225829ms
2025/07/12 01:28:12 DEBUG : pacer: Reducing sleep to 150.169371ms
2025/07/12 01:28:13 DEBUG : pacer: Reducing sleep to 112.627028ms
2025/07/12 01:28:13 DEBUG : A4/B1/C1: Rmdir: contains trashed file: "four"
2025/07/12 01:28:13 DEBUG : pacer: Reducing sleep to 100ms
2025/07/12 01:28:14 DEBUG : A4/B1: Rmdir: contains trashed file: "C3"
2025/07/12 01:28:14 DEBUG : A4/B1: Rmdir: contains trashed file: "C2"
2025/07/12 01:28:14 DEBUG : A4/B1: Rmdir: contains trashed file: "C1"
2025/07/12 01:28:14 DEBUG : A4/B1: Rmdir: contains trashed file: "three"
2025/07/12 01:28:15 DEBUG : A4: Rmdir: contains trashed file: "B2"
2025/07/12 01:28:15 DEBUG : A4: Rmdir: contains trashed file: "B1"
2025/07/12 01:28:15 DEBUG : A4: Rmdir: contains trashed file: "two"
2025/07/12 01:28:15 DEBUG : A4: Rmdir: contains trashed file: "one"
--- PASS: TestDirMove (85.25s)
PASS
2025/07/12 01:28:17 DEBUG : PikPak root 'rclone-test-mijaxet9rebu': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestDirMove$'" - Finished OK in 1m27.968383809s (try 2/5)