"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsDirMove|FsPurge|FsPurgeRoot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries)$'" - Starting (try 3/5) === RUN TestIntegration fstests.go:438: Using remote "TestPikPak:" 2026/05/09 01:29:33 DEBUG : Creating backend with remote "TestPikPak:rclone-test-semarej9hipe" 2026/05/09 01:29:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsPutFiles 2026/05/09 01:29:36 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2026/05/09 01:29:36 DEBUG : file name.txt: gcid = 2b0a87de4702292c4f739b55c9cad26be686f4ef 2026/05/09 01:29:41 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: calculating gcid: failed to unwrap object from src: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt 2026/05/09 01:29:41 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: gcid = 63184824322959521acf075fbd34f96da0f0e6cd 2026/05/09 01:29:43 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:29:43 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2026/05/09 01:29:44 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:29:44 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/05/09 01:29:44 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:29:44 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/05/09 01:29:44 DEBUG : pacer: Reducing sleep to 600ms 2026/05/09 01:29:45 DEBUG : pacer: Reducing sleep to 450ms 2026/05/09 01:29:46 DEBUG : pacer: Reducing sleep to 337.5ms 2026/05/09 01:29:46 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2026/05/09 01:29:46 DEBUG : file name.txt: gcid = 564f22d6d3baa18a955d541e8f7631adb32e0494 2026/05/09 01:29:46 DEBUG : pacer: Reducing sleep to 253.125ms 2026/05/09 01:29:47 DEBUG : pacer: Reducing sleep to 189.84375ms 2026/05/09 01:29:48 DEBUG : pacer: Reducing sleep to 142.382812ms 2026/05/09 01:29:49 DEBUG : pacer: Reducing sleep to 106.787109ms 2026/05/09 01:29:49 DEBUG : pacer: Reducing sleep to 100ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/05/09 01:29:51 DEBUG : dirToPurge/fileToPurge.txt: calculating gcid: failed to unwrap object from src: dirToPurge/fileToPurge.txt 2026/05/09 01:29:51 DEBUG : dirToPurge/fileToPurge.txt: gcid = 3ffd555838d45750393e2dc6539a16451ad085d4 2026/05/09 01:29:53 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:29:53 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2026/05/09 01:29:53 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:29:53 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/05/09 01:29:54 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:29:54 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/05/09 01:29:54 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:29:54 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/05/09 01:29:55 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:29:55 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/09 01:29:57 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:29:59 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:01 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:03 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:05 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:05 DEBUG : fileToPurge.txt: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOs9LilaTXN5L5GxxIb1NTvIo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x3d02274c2340), FileID:"VOs9LilCTXN5L5GxxIb1NTvFo2", FileName:"fileToPurge.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1b98d1c0, ext:63913886991, loc:(*time.Location)(0x3d02271d9880)}, UpdatedTime:api.Time{wall:0x1c8cf5c0, ext:63913886991, loc:(*time.Location)(0x3d02271d9880)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/09 01:30:07 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/09 01:30:09 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/09 01:30:10 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/09 01:30:10 DEBUG : fileToPurge.txt: waiting 500ms for the cancellation to be effective fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:224 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:214 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1166 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOs9LilaTXN5L5GxxIb1NTvIo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x3d02274c2340), FileID:"VOs9LilCTXN5L5GxxIb1NTvFo2", FileName:"fileToPurge.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1b98d1c0, ext:63913886991, loc:(*time.Location)(0x3d02271d9880)}, UpdatedTime:api.Time{wall:0x1c8cf5c0, ext:63913886991, loc:(*time.Location)(0x3d02271d9880)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsPutFiles/FsPurge Messages: Put === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/05/09 01:30:11 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/09 01:30:12 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/09 01:30:13 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/09 01:30:13 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: calculating gcid: failed to unwrap object from src: dirToPurgeFromRoot/fileToPurgeFromRoot.txt 2026/05/09 01:30:13 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: gcid = 09f1a1248a3e6f1849e94ace06790342bb62d0f2 2026/05/09 01:30:13 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/09 01:30:14 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/09 01:30:15 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:15 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/09 01:30:15 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:15 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/09 01:30:16 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:16 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/09 01:30:17 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:17 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/09 01:30:19 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2026/05/09 01:30:19 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:20 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:22 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:24 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:26 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:28 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:30:28 DEBUG : fileToPurgeFromRoot.txt: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOs9Lo9C-Oxriql1yeRsXCvHo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x3d0227420f40), FileID:"VOs9Lo8u-Oxriql1yeRsXCvEo2", FileName:"fileToPurgeFromRoot.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1ed0cb40, ext:63913887013, loc:(*time.Location)(0x3d02271d9880)}, UpdatedTime:api.Time{wall:0x202fbf00, ext:63913887013, loc:(*time.Location)(0x3d02271d9880)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/09 01:30:30 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/09 01:30:32 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/09 01:30:34 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/09 01:30:34 DEBUG : fileToPurgeFromRoot.txt: waiting 500ms for the cancellation to be effective fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:224 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:214 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1203 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOs9Lo9C-Oxriql1yeRsXCvHo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x3d0227420f40), FileID:"VOs9Lo8u-Oxriql1yeRsXCvEo2", FileName:"fileToPurgeFromRoot.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1ed0cb40, ext:63913887013, loc:(*time.Location)(0x3d02271d9880)}, UpdatedTime:api.Time{wall:0x202fbf00, ext:63913887013, loc:(*time.Location)(0x3d02271d9880)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot Messages: Put === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/05/09 01:30:34 DEBUG : Creating backend with remote "TestPikPak:rclone-test-yikopir1yuho" 2026/05/09 01:30:39 DEBUG : pacer: Reducing sleep to 632.8125ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1494 Error: Not equal: expected: []string{"new_name", "new_name/sub_new_name", "new_name/sub_new_name/hello? sausage", "new_name/sub_new_name/hello? sausage/êé", "new_name/sub_new_name/hello? sausage/êé/Hello, 世界", "new_name/sub_new_name/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} actual : []string{"new_name", "new_name/sub_new_name", "new_name/sub_new_name/dirToPurge", "new_name/sub_new_name/dirToPurgeFromRoot", "new_name/sub_new_name/hello? sausage", "new_name/sub_new_name/hello? sausage/êé", "new_name/sub_new_name/hello? sausage/êé/Hello, 世界", "new_name/sub_new_name/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} Diff: --- Expected +++ Actual @@ -1,4 +1,6 @@ -([]string) (len=6) { +([]string) (len=8) { (string) (len=8) "new_name", (string) (len=21) "new_name/sub_new_name", + (string) (len=32) "new_name/sub_new_name/dirToPurge", + (string) (len=40) "new_name/sub_new_name/dirToPurgeFromRoot", (string) (len=36) "new_name/sub_new_name/hello? sausage", Test: TestIntegration/FsMkdir/FsPutFiles/FsDirMove Messages: directories 2026/05/09 01:30:53 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/09 01:30:54 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/09 01:30:55 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/09 01:30:55 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/09 01:30:56 DEBUG : pacer: Reducing sleep to 150.169371ms 2026/05/09 01:30:56 DEBUG : pacer: Reducing sleep to 112.627028ms 2026/05/09 01:30:56 DEBUG : pacer: Reducing sleep to 100ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1508 Error: Not equal: expected: []string{"hello? sausage", "hello? sausage/êé", "hello? sausage/êé/Hello, 世界", "hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} actual : []string{"dirToPurge", "dirToPurgeFromRoot", "hello? sausage", "hello? sausage/êé", "hello? sausage/êé/Hello, 世界", "hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) (len=4) { +([]string) (len=6) { + (string) (len=10) "dirToPurge", + (string) (len=18) "dirToPurgeFromRoot", (string) (len=14) "hello? sausage", Test: TestIntegration/FsMkdir/FsPutFiles/FsDirMove Messages: directories 2026/05/09 01:31:08 DEBUG : PikPak root 'rclone-test-yikopir1yuho': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1920: Opening root remote "TestPikPak:" path "rclone-test-semarej9hipe" from "TestPikPak:rclone-test-semarej9hipe" 2026/05/09 01:31:09 DEBUG : Creating backend with remote "TestPikPak:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1943 Error: Not equal: expected: []string{"rclone-test-semarej9hipe/hello? sausage", "rclone-test-semarej9hipe/hello? sausage/êé", "rclone-test-semarej9hipe/hello? sausage/êé/Hello, 世界", "rclone-test-semarej9hipe/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} actual : []string{"rclone-test-semarej9hipe/dirToPurge", "rclone-test-semarej9hipe/dirToPurgeFromRoot", "rclone-test-semarej9hipe/hello? sausage", "rclone-test-semarej9hipe/hello? sausage/êé", "rclone-test-semarej9hipe/hello? sausage/êé/Hello, 世界", "rclone-test-semarej9hipe/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) (len=4) { +([]string) (len=6) { + (string) (len=35) "rclone-test-semarej9hipe/dirToPurge", + (string) (len=43) "rclone-test-semarej9hipe/dirToPurgeFromRoot", (string) (len=39) "rclone-test-semarej9hipe/hello? sausage", Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List Messages: directories === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries fstest.go:398: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:398 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1950 Error: Not equal: expected: []string{"rclone-test-semarej9hipe/hello? sausage"} actual : []string{"rclone-test-semarej9hipe/dirToPurge", "rclone-test-semarej9hipe/dirToPurgeFromRoot", "rclone-test-semarej9hipe/hello? sausage"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) (len=1) { +([]string) (len=3) { + (string) (len=35) "rclone-test-semarej9hipe/dirToPurge", + (string) (len=43) "rclone-test-semarej9hipe/dirToPurgeFromRoot", (string) (len=39) "rclone-test-semarej9hipe/hello? sausage" Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries Messages: directories not equal 2026/05/09 01:31:23 ERROR : error listing: directory not found --- FAIL: TestIntegration (110.46s) --- FAIL: TestIntegration/FsMkdir (108.09s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles (105.95s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsPurge (20.63s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (23.67s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (35.00s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot (12.15s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (11.70s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.45s) FAIL exit status 1 FAIL github.com/rclone/rclone/backend/pikpak 110.474s "go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsDirMove|FsPurge|FsPurgeRoot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries)$'" - Finished ERROR in 1m51.424137153s (try 3/5): exit status 1: Failed [TestIntegration/FsMkdir/FsPutFiles/FsPurge TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot TestIntegration/FsMkdir/FsPutFiles/FsDirMove TestIntegration/FsMkdir/FsPutFiles/FromRoot/List TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries]