"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:442: Using remote "TestPikPak:" 2025/02/08 04:29:57 DEBUG : Creating backend with remote "TestPikPak:rclone-test-yiraxul5fiba" 2025/02/08 04:29:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsPutFiles 2025/02/08 04:30:00 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2025/02/08 04:30:00 DEBUG : file name.txt: gcid = a57a552d610beeff503eaccc7e1c96531d215362 2025/02/08 04:30:05 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: calculating gcid: failed to unwrap object from src: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt 2025/02/08 04:30:05 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: gcid = 3b8d0888b114cd9e94afec0ecede497438dd7d89 2025/02/08 04:30:08 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2025/02/08 04:30:08 DEBUG : file name.txt: gcid = c0adb0a668591c37c51f57122840e002c1fd157b 2025/02/08 04:30:12 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/02/08 04:30:13 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/02/08 04:30:13 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/02/08 04:30:13 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/02/08 04:30:13 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/02/08 04:30:13 DEBUG : pacer: low level retry 3/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/02/08 04:30:13 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/02/08 04:30:14 DEBUG : pacer: Reducing sleep to 600ms 2025/02/08 04:30:15 DEBUG : pacer: Reducing sleep to 450ms 2025/02/08 04:30:15 DEBUG : pacer: Reducing sleep to 337.5ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2025/02/08 04:30:16 DEBUG : pacer: Reducing sleep to 253.125ms 2025/02/08 04:30:16 DEBUG : pacer: Reducing sleep to 189.84375ms 2025/02/08 04:30:16 DEBUG : pacer: Reducing sleep to 142.382812ms 2025/02/08 04:30:16 DEBUG : dirToPurge/fileToPurge.txt: calculating gcid: failed to unwrap object from src: dirToPurge/fileToPurge.txt 2025/02/08 04:30:16 DEBUG : dirToPurge/fileToPurge.txt: gcid = 3b2f7cc372e54e73096702581b9aa67b90d4387b 2025/02/08 04:30:18 DEBUG : pacer: Reducing sleep to 106.787109ms 2025/02/08 04:30:19 DEBUG : pacer: Reducing sleep to 100ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2025/02/08 04:30:25 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: calculating gcid: failed to unwrap object from src: dirToPurgeFromRoot/fileToPurgeFromRoot.txt 2025/02/08 04:30:25 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: gcid = 3b25b12904e0a042f2dc0e4446c061b5a8f19402 2025/02/08 04:30:27 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/08 04:30:27 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/02/08 04:30:28 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/08 04:30:28 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/02/08 04:30:28 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/08 04:30:28 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/02/08 04:30:28 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/08 04:30:28 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/02/08 04:30:29 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/08 04:30:29 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/02/08 04:30:31 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/08 04:30:33 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/08 04:30:35 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/08 04:30:37 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/08 04:30:39 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/02/08 04:30:39 DEBUG : fileToPurgeFromRoot.txt: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOIYofcQK910QGfwHv1L0zHuo1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc00009bc80), FileID:"VOIYofc9K910QGfwHv1L0zHso1", FileName:"fileToPurgeFromRoot.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x30d0f2c0, ext:63874585825, loc:(*time.Location)(0xc0004b99d0)}, UpdatedTime:api.Time{wall:0x33428f00, ext:63874585825, loc:(*time.Location)(0xc0004b99d0)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2025/02/08 04:30:41 DEBUG : pacer: Reducing sleep to 1.5s 2025/02/08 04:30:43 DEBUG : pacer: Reducing sleep to 1.125s 2025/02/08 04:30:44 DEBUG : pacer: Reducing sleep to 843.75ms 2025/02/08 04:30:44 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:"VOIYofcQK910QGfwHv1L0zHuo1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc00009bc80), FileID:"VOIYofc9K910QGfwHv1L0zHso1", FileName:"fileToPurgeFromRoot.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x30d0f2c0, ext:63874585825, loc:(*time.Location)(0xc0004b99d0)}, UpdatedTime:api.Time{wall:0x33428f00, ext:63874585825, loc:(*time.Location)(0xc0004b99d0)}, 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/02/08 04:30:45 DEBUG : Creating backend with remote "TestPikPak:rclone-test-legufej0fizo" 2025/02/08 04:30:48 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/02/08 04:31:05 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/02/08 04:31:06 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/02/08 04:31:07 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/02/08 04:31:07 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/02/08 04:31:07 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/02/08 04:31:08 DEBUG : pacer: Reducing sleep to 112.627028ms 2025/02/08 04:31:08 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 2025/02/08 04:31:11 DEBUG : TestPikPak: Loaded invalid token from config file - ignoring 2025/02/08 04:31:12 DEBUG : Saving config "token" in section "TestPikPak" of the config file 2025/02/08 04:31:12 DEBUG : TestPikPak: Saved new token in config file 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/02/08 04:31:21 DEBUG : TestPikPak: Loaded fresh token from config file 2025/02/08 04:31:21 DEBUG : TestPikPak: Loaded new refresh token from config file 2025/02/08 04:31:22 DEBUG : PikPak root 'rclone-test-legufej0fizo': Purge remote 2025/02/08 04:31:24 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/02/08 04:31:24 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/02/08 04:31:24 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/02/08 04:31:24 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/02/08 04:31:24 DEBUG : pacer: Reducing sleep to 300ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1915: Opening root remote "TestPikPak:" path "rclone-test-yiraxul5fiba" from "TestPikPak:rclone-test-yiraxul5fiba" 2025/02/08 04:31:24 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-yiraxul5fiba/hello? sausage", "rclone-test-yiraxul5fiba/hello? sausage/êé", "rclone-test-yiraxul5fiba/hello? sausage/êé/Hello, 世界", "rclone-test-yiraxul5fiba/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} actual : []string{"rclone-test-yiraxul5fiba/dirToPurgeFromRoot", "rclone-test-yiraxul5fiba/hello? sausage", "rclone-test-yiraxul5fiba/hello? sausage/êé", "rclone-test-yiraxul5fiba/hello? sausage/êé/Hello, 世界", "rclone-test-yiraxul5fiba/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=4) { +([]string) (len=5) { + (string) (len=43) "rclone-test-yiraxul5fiba/dirToPurgeFromRoot", (string) (len=39) "rclone-test-yiraxul5fiba/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-yiraxul5fiba/hello? sausage"} actual : []string{"rclone-test-yiraxul5fiba/dirToPurgeFromRoot", "rclone-test-yiraxul5fiba/hello? sausage"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=43) "rclone-test-yiraxul5fiba/dirToPurgeFromRoot", (string) (len=39) "rclone-test-yiraxul5fiba/hello? sausage" Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries Messages: directories not equal 2025/02/08 04:31:41 ERROR : : error listing: directory not found --- FAIL: TestIntegration (104.11s) --- FAIL: TestIntegration/FsMkdir (101.94s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles (99.12s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (8.43s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (21.04s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (39.61s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot (14.27s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (13.68s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.59s) FAIL exit status 1 FAIL github.com/rclone/rclone/backend/pikpak 104.120s "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 1m44.888103821s (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]