"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsDirMove|FsPurgeRoot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries)$'" - Starting (try 3/5) === RUN TestIntegration fstests.go:442: Using remote "TestPikPak:" 2025/01/02 02:34:41 DEBUG : Creating backend with remote "TestPikPak:rclone-test-xewedol2nufu" 2025/01/02 02:34:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsPutFiles 2025/01/02 02:34:44 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2025/01/02 02:34:44 DEBUG : file name.txt: gcid = eeffdd39aa7f62208d7984396af9404379273d7a 2025/01/02 02:34:49 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: calculating gcid: failed to unwrap object from src: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt 2025/01/02 02:34:49 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: gcid = dc199ab51a7505dec16e4362e5b6cb8a054ae38f 2025/01/02 02:34:51 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:34:51 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/01/02 02:34:51 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:34:51 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/01/02 02:34:52 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:34:52 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/01/02 02:34:52 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:34:52 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/01/02 02:34:53 DEBUG : pacer: Reducing sleep to 1.2s 2025/01/02 02:34:55 DEBUG : pacer: Reducing sleep to 900ms 2025/01/02 02:34:56 DEBUG : pacer: Reducing sleep to 675ms 2025/01/02 02:34:56 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2025/01/02 02:34:56 DEBUG : file name.txt: gcid = c32c6467900e6eee13cd99299b44a75a2b46aa70 2025/01/02 02:34:57 DEBUG : pacer: Reducing sleep to 506.25ms 2025/01/02 02:34:58 DEBUG : pacer: Reducing sleep to 379.6875ms 2025/01/02 02:34:59 DEBUG : pacer: Reducing sleep to 284.765625ms 2025/01/02 02:35:00 DEBUG : pacer: Reducing sleep to 213.574218ms 2025/01/02 02:35:00 DEBUG : pacer: Reducing sleep to 160.180663ms 2025/01/02 02:35:00 DEBUG : pacer: Reducing sleep to 120.135497ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2025/01/02 02:35:01 DEBUG : pacer: Reducing sleep to 100ms 2025/01/02 02:35:01 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: calculating gcid: failed to unwrap object from src: dirToPurgeFromRoot/fileToPurgeFromRoot.txt 2025/01/02 02:35:01 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: gcid = 00bce98f1568de7e30bb683e39161d5e4a4b1fc0 2025/01/02 02:35:03 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:35:03 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/01/02 02:35:04 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:35:04 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/01/02 02:35:04 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:35:04 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/01/02 02:35:04 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:35:04 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/01/02 02:35:05 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:35:05 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/01/02 02:35:07 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:35:09 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:35:11 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:35:13 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:35:15 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/02 02:35:15 DEBUG : fileToPurgeFromRoot.txt: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOFZrRSeVk1S_QJiZFtqG3rgo1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc0002b0c40), FileID:"VOFZrRSPVk1S_QJiZFtqG3qso1", FileName:"fileToPurgeFromRoot.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x339e1c80, ext:63871382101, loc:(*time.Location)(0xc0000e29a0)}, UpdatedTime:api.Time{wall:0x34b0c500, ext:63871382101, loc:(*time.Location)(0xc0000e29a0)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2025/01/02 02:35:19 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/01/02 02:35:19 DEBUG : pacer: Reducing sleep to 1.5s 2025/01/02 02:35:20 DEBUG : pacer: Reducing sleep to 1.125s 2025/01/02 02:35:21 DEBUG : pacer: Reducing sleep to 843.75ms 2025/01/02 02:35:21 DEBUG : fileToPurgeFromRoot.txt: waiting 500ms for the cancellation to be effective fstests.go:145: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:161 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:223 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:213 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1202 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOFZrRSeVk1S_QJiZFtqG3rgo1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc0002b0c40), FileID:"VOFZrRSPVk1S_QJiZFtqG3qso1", FileName:"fileToPurgeFromRoot.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x339e1c80, ext:63871382101, loc:(*time.Location)(0xc0000e29a0)}, UpdatedTime:api.Time{wall:0x34b0c500, ext:63871382101, loc:(*time.Location)(0xc0000e29a0)}, 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 2025/01/02 02:35:22 DEBUG : Creating backend with remote "TestPikPak:rclone-test-lagador8zuru" 2025/01/02 02:35:25 DEBUG : pacer: Reducing sleep to 632.8125ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1489 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/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,5 @@ -([]string) (len=6) { +([]string) (len=7) { (string) (len=8) "new_name", (string) (len=21) "new_name/sub_new_name", + (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 2025/01/02 02:35:39 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/01/02 02:35:40 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/01/02 02:35:41 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/01/02 02:35:41 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/01/02 02:35:42 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/01/02 02:35:42 DEBUG : pacer: Reducing sleep to 112.627028ms 2025/01/02 02:35:42 DEBUG : pacer: Reducing sleep to 100ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1503 Error: Not equal: expected: []string{"hello? sausage", "hello? sausage/êé", "hello? sausage/êé/Hello, 世界", "hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} actual : []string{"dirToPurgeFromRoot", "hello? sausage", "hello? sausage/êé", "hello? sausage/êé/Hello, 世界", "hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=4) { +([]string) (len=5) { + (string) (len=18) "dirToPurgeFromRoot", (string) (len=14) "hello? sausage", Test: TestIntegration/FsMkdir/FsPutFiles/FsDirMove Messages: directories 2025/01/02 02:35:54 DEBUG : PikPak root 'rclone-test-lagador8zuru': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1915: Opening root remote "TestPikPak:" path "rclone-test-xewedol2nufu" from "TestPikPak:rclone-test-xewedol2nufu" 2025/01/02 02:35:55 DEBUG : Creating backend with remote "TestPikPak:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1935 Error: Not equal: expected: []string{"rclone-test-xewedol2nufu/hello? sausage", "rclone-test-xewedol2nufu/hello? sausage/êé", "rclone-test-xewedol2nufu/hello? sausage/êé/Hello, 世界", "rclone-test-xewedol2nufu/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} actual : []string{"rclone-test-xewedol2nufu/dirToPurgeFromRoot", "rclone-test-xewedol2nufu/hello? sausage", "rclone-test-xewedol2nufu/hello? sausage/êé", "rclone-test-xewedol2nufu/hello? sausage/êé/Hello, 世界", "rclone-test-xewedol2nufu/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=4) { +([]string) (len=5) { + (string) (len=43) "rclone-test-xewedol2nufu/dirToPurgeFromRoot", (string) (len=39) "rclone-test-xewedol2nufu/hello? sausage", Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List Messages: directories === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries fstest.go:392: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:392 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1942 Error: Not equal: expected: []string{"rclone-test-xewedol2nufu/hello? sausage"} actual : []string{"rclone-test-xewedol2nufu/dirToPurgeFromRoot", "rclone-test-xewedol2nufu/hello? sausage"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=43) "rclone-test-xewedol2nufu/dirToPurgeFromRoot", (string) (len=39) "rclone-test-xewedol2nufu/hello? sausage" Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries Messages: directories not equal 2025/01/02 02:36:10 ERROR : : error listing: directory not found --- FAIL: TestIntegration (89.14s) --- FAIL: TestIntegration/FsMkdir (87.12s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles (84.59s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (21.40s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (32.86s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot (13.67s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (13.04s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.63s) FAIL exit status 1 FAIL github.com/rclone/rclone/backend/pikpak 89.156s "go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsDirMove|FsPurgeRoot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries)$'" - Finished ERROR in 1m30.713666876s (try 3/5): exit status 1: Failed [TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot TestIntegration/FsMkdir/FsPutFiles/FsDirMove TestIntegration/FsMkdir/FsPutFiles/FromRoot/List TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries]