"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestDirMove$'" - Starting (try 2/5)
2025/10/29 03:17:03 DEBUG : Creating backend with remote "TestPikPak:rclone-test-gamobeh1gopi"
2025/10/29 03:17:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/29 03:17:05 DEBUG : Creating backend with remote "/tmp/rclone1580374607"
=== RUN   TestDirMove
    run.go:180: Remote "PikPak root 'rclone-test-gamobeh1gopi'", Local "Local file system at /tmp/rclone1580374607", Modify Window "876000h0m0s"
2025/10/29 03:17:07 DEBUG : A1/one: calculating gcid: failed to unwrap object from src: A1/one
2025/10/29 03:17:07 DEBUG : A1/one: gcid = 74445cd742af268fd22866da0d12758951d638f7
2025/10/29 03:17:07 DEBUG : A1/two: calculating gcid: failed to unwrap object from src: A1/two
2025/10/29 03:17:07 DEBUG : A1/two: gcid = a41bee51488264c01b96de959a9142af1a75205e
2025/10/29 03:17:09 DEBUG : A1/B1/three: calculating gcid: failed to unwrap object from src: A1/B1/three
2025/10/29 03:17:09 DEBUG : A1/B1/three: gcid = aa5f498bee9d8e3a091610f6b4456e1a09763c27
2025/10/29 03:17:10 DEBUG : A1/B1/C1/four: calculating gcid: failed to unwrap object from src: A1/B1/C1/four
2025/10/29 03:17:10 DEBUG : A1/B1/C1/four: gcid = 8b8f52fdefe3ab828c814dda9a27cc4d9355c89b
2025/10/29 03:17:11 DEBUG : A1/B1/C2/five: calculating gcid: failed to unwrap object from src: A1/B1/C2/five
2025/10/29 03:17:11 DEBUG : A1/B1/C2/five: gcid = c5a78b0666272ddddef4fa491d9527439267a27a
2025/10/29 03:17:12 INFO  : A1/B2: Making directory
2025/10/29 03:17:12 INFO  : A1/B1/C3: Making directory
2025/10/29 03:17:25 INFO  : A2/two: Moved (server-side) to: A3/two
2025/10/29 03:17:26 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2025/10/29 03:17:26 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2025/10/29 03:17:26 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2025/10/29 03:17:26 INFO  : A2/one: Moved (server-side) to: A3/one
2025/10/29 03:17:27 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/10/29 03:17:27 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/10/29 03:17:28 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/10/29 03:17:28 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/10/29 03:17:28 DEBUG : pacer: low level retry 3/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/10/29 03:17:28 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2025/10/29 03:17:28 DEBUG : pacer: low level retry 4/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/10/29 03:17:28 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2025/10/29 03:17:29 DEBUG : pacer: low level retry 5/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/10/29 03:17:29 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/10/29 03:17:31 DEBUG : pacer: low level retry 6/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/10/29 03:17:33 DEBUG : pacer: low level retry 7/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/10/29 03:17:35 DEBUG : pacer: low level retry 8/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/10/29 03:17:37 DEBUG : pacer: low level retry 9/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
2025/10/29 03:17:39 DEBUG : pacer: low level retry 10/10 (error trash (trash) is still in PHASE_TYPE_PENDING)
    operations_test.go:1413: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1413
        	Error:      	Received unexpected error:
        	            	RenameDir rmdir: delete object failed: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOchy5ooaoqHECeYx7I7FFMlo2", Name:"trash", Type:"trash", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc000766000), FileID:"", FileName:"", FileSize:"0", Message:"Pending", CreatedTime:api.Time{wall:0x39757900, ext:63897304646, loc:(*time.Location)(0xc000401030)}, UpdatedTime:api.Time{wall:0x39757900, ext:63897304646, loc:(*time.Location)(0xc000401030)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""}
        	Test:       	TestDirMove
2025/10/29 03:17:41 DEBUG : pacer: Reducing sleep to 1.5s
2025/10/29 03:17:43 DEBUG : pacer: Reducing sleep to 1.125s
2025/10/29 03:17:44 DEBUG : pacer: Reducing sleep to 843.75ms
2025/10/29 03:17:45 DEBUG : pacer: Reducing sleep to 632.8125ms
2025/10/29 03:17:46 DEBUG : pacer: Reducing sleep to 474.609375ms
2025/10/29 03:17:47 DEBUG : pacer: Reducing sleep to 355.957031ms
2025/10/29 03:17:48 DEBUG : pacer: Reducing sleep to 266.967773ms
2025/10/29 03:17:48 DEBUG : pacer: Reducing sleep to 200.225829ms
2025/10/29 03:17:48 DEBUG : pacer: Reducing sleep to 150.169371ms
2025/10/29 03:17:49 DEBUG : pacer: Reducing sleep to 112.627028ms
2025/10/29 03:17:49 DEBUG : pacer: Reducing sleep to 100ms
2025/10/29 03:17:56 DEBUG : A3/B1/C2: Rmdir: contains trashed file: "five"
2025/10/29 03:17:58 DEBUG : A3/B1/C1: Rmdir: contains trashed file: "four"
2025/10/29 03:17:59 DEBUG : A3/B1: Rmdir: contains trashed file: "C3"
2025/10/29 03:17:59 DEBUG : A3/B1: Rmdir: contains trashed file: "C2"
2025/10/29 03:17:59 DEBUG : A3/B1: Rmdir: contains trashed file: "C1"
2025/10/29 03:17:59 DEBUG : A3/B1: Rmdir: contains trashed file: "three"
2025/10/29 03:18:01 DEBUG : A3: Rmdir: contains trashed file: "B2"
2025/10/29 03:18:01 DEBUG : A3: Rmdir: contains trashed file: "B1"
2025/10/29 03:18:01 DEBUG : A3: Rmdir: contains trashed file: "two"
2025/10/29 03:18:01 DEBUG : A3: Rmdir: contains trashed file: "one"
2025/10/29 03:18:07 DEBUG : A2/B1: Rmdir: contains trashed file: "C3"
2025/10/29 03:18:07 DEBUG : A2/B1: Rmdir: contains trashed file: "C2"
2025/10/29 03:18:07 DEBUG : A2/B1: Rmdir: contains trashed file: "C1"
2025/10/29 03:18:08 DEBUG : A2: Rmdir: contains trashed file: "B2"
2025/10/29 03:18:08 DEBUG : A2: Rmdir: contains trashed file: "B1"
--- FAIL: TestDirMove (64.86s)
FAIL
2025/10/29 03:18:09 DEBUG : PikPak root 'rclone-test-gamobeh1gopi': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestDirMove$'" - Finished ERROR in 1m7.554758457s (try 2/5): exit status 1: Failed [TestDirMove]