"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|invalid_UTF-8|trailing_VT|trailing_dot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsDirMove|FsListDirFile2|FsListLevel2|FsListR|FsPurge|FsPurgeRoot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries)$'" - Starting (try 2/5) === RUN TestIntegration fstests.go:442: Using remote "TestPikPak:" 2025/01/12 03:42:57 DEBUG : Creating backend with remote "TestPikPak:rclone-test-xevamup8huba" 2025/01/12 03:42:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:711: testing "trailing VT␋" 2025/01/12 03:43:00 DEBUG : trailing VT␋/trailing VT␋: calculating gcid: failed to unwrap object from src: trailing VT␋/trailing VT␋ 2025/01/12 03:43:00 DEBUG : trailing VT␋/trailing VT␋: gcid = bb64a8df518adbe9b363b33082f09b9df96c3cea 2025/01/12 03:43:02 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:02 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/01/12 03:43:03 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:03 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/01/12 03:43:03 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:03 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/01/12 03:43:03 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:03 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/01/12 03:43:04 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:04 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/01/12 03:43:06 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:08 DEBUG : pacer: Reducing sleep to 1.5s 2025/01/12 03:43:10 DEBUG : pacer: Reducing sleep to 1.125s 2025/01/12 03:43:11 DEBUG : pacer: Reducing sleep to 843.75ms 2025/01/12 03:43:13 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/01/12 03:43:13 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/01/12 03:43:14 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/01/12 03:43:15 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/01/12 03:43:15 DEBUG : trailing VT␋: Rmdir: contains trashed file: "trailing VT␋" 2025/01/12 03:43:15 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/01/12 03:43:16 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/01/12 03:43:16 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:711: testing "trailing dot." 2025/01/12 03:43:16 DEBUG : pacer: Reducing sleep to 100ms 2025/01/12 03:43:17 DEBUG : trailing dot./trailing dot.: calculating gcid: failed to unwrap object from src: trailing dot./trailing dot. 2025/01/12 03:43:17 DEBUG : trailing dot./trailing dot.: gcid = a18639bde3f24ea14399138c77e7f636ae3ca958 2025/01/12 03:43:19 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:19 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/01/12 03:43:19 DEBUG : pacer: Reducing sleep to 150ms 2025/01/12 03:43:20 DEBUG : pacer: Reducing sleep to 112.5ms 2025/01/12 03:43:20 DEBUG : pacer: Reducing sleep to 100ms 2025/01/12 03:43:22 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/01/12 03:43:23 DEBUG : trailing dot.: Rmdir: contains trashed file: "trailing dot." === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:711: testing "invalid utf-8\xfe" 2025/01/12 03:43:25 DEBUG : invalid utf-8/invalid utf-8: calculating gcid: failed to unwrap object from src: invalid utf-8/invalid utf-8 2025/01/12 03:43:25 DEBUG : invalid utf-8/invalid utf-8: gcid = 45e691bb294a4cff247ada3680dfb02c3d0a7dbe 2025/01/12 03:43:27 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:27 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/01/12 03:43:27 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:27 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/01/12 03:43:28 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:28 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/01/12 03:43:28 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:28 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/01/12 03:43:29 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:29 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/01/12 03:43:30 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:32 DEBUG : pacer: Reducing sleep to 1.5s 2025/01/12 03:43:34 DEBUG : pacer: Reducing sleep to 1.125s 2025/01/12 03:43:36 DEBUG : pacer: Reducing sleep to 843.75ms 2025/01/12 03:43:37 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/01/12 03:43:38 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/01/12 03:43:39 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/01/12 03:43:39 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/01/12 03:43:39 DEBUG : invalid utf-8: Rmdir: contains trashed file: "invalid utf-8\xfe" 2025/01/12 03:43:40 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/01/12 03:43:40 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/01/12 03:43:41 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:711: testing "test%46.txt" 2025/01/12 03:43:41 DEBUG : pacer: Reducing sleep to 100ms 2025/01/12 03:43:41 DEBUG : test%46.txt/test%46.txt: calculating gcid: failed to unwrap object from src: test%46.txt/test%46.txt 2025/01/12 03:43:41 DEBUG : test%46.txt/test%46.txt: gcid = c2243eec73dffb40696a259eb463b185a5b6509c 2025/01/12 03:43:44 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:44 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/01/12 03:43:44 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:44 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/01/12 03:43:44 DEBUG : pacer: Reducing sleep to 300ms 2025/01/12 03:43:45 DEBUG : pacer: Reducing sleep to 225ms 2025/01/12 03:43:45 DEBUG : pacer: Reducing sleep to 168.75ms 2025/01/12 03:43:45 DEBUG : pacer: Reducing sleep to 126.5625ms 2025/01/12 03:43:46 DEBUG : pacer: Reducing sleep to 100ms 2025/01/12 03:43:47 DEBUG : test%46.txt: Rmdir: contains trashed file: "test%46.txt" === RUN TestIntegration/FsMkdir/FsPutFiles 2025/01/12 03:43:48 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2025/01/12 03:43:48 DEBUG : file name.txt: gcid = 8c45017348c0f86a8c73902bb2bdfc67b2c6a9c9 2025/01/12 03:43:50 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:50 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/01/12 03:43:51 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:51 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/01/12 03:43:51 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:51 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/01/12 03:43:51 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:51 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/01/12 03:43:52 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:43:52 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/01/12 03:43:54 DEBUG : pacer: Reducing sleep to 1.5s 2025/01/12 03:43:56 DEBUG : pacer: Reducing sleep to 1.125s 2025/01/12 03:43:57 DEBUG : pacer: Reducing sleep to 843.75ms 2025/01/12 03:43:58 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/01/12 03:43:59 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/01/12 03:44:00 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/01/12 03:44:00 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/01/12 03:44:01 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/01/12 03:44:01 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/01/12 03:44:01 DEBUG : pacer: Reducing sleep to 112.627028ms 2025/01/12 03:44:02 DEBUG : pacer: Reducing sleep to 100ms 2025/01/12 03:44:02 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: calculating gcid: failed to unwrap object from src: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt 2025/01/12 03:44:02 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: gcid = f3d3d74fb03098a98ad261a475cf9ca8d97c5872 2025/01/12 03:44:05 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2025/01/12 03:44:05 DEBUG : file name.txt: gcid = 66147d1ff0f202b62cab472ea010718f37aabe2f 2025/01/12 03:44:08 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:44:08 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/01/12 03:44:08 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:44:08 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/01/12 03:44:08 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:44:08 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/01/12 03:44:09 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:44:09 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/01/12 03:44:09 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:44:09 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/01/12 03:44:11 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:44:13 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:44:15 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:44:17 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:44:19 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2025/01/12 03:44:19 DEBUG : rcloneTempvusudut0: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOGNb8hF1Q3_bfGh7dz3YXB6o1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc00035d540), FileID:"VOGNb8gu1Q3_bfGh7dz3YXB4o1", FileName:"rcloneTempvusudut0", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x39b28200, ext:63872250245, loc:(*time.Location)(0xc0001d8a80)}, UpdatedTime:api.Time{wall:0x0, ext:63872250246, loc:(*time.Location)(0xc0001d8a80)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2025/01/12 03:44:21 DEBUG : pacer: Reducing sleep to 1.5s 2025/01/12 03:44:23 DEBUG : pacer: Reducing sleep to 1.125s 2025/01/12 03:44:25 DEBUG : pacer: Reducing sleep to 843.75ms 2025/01/12 03:44:25 DEBUG : rcloneTempvusudut0: 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:959 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOGNb8hF1Q3_bfGh7dz3YXB6o1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc00035d540), FileID:"VOGNb8gu1Q3_bfGh7dz3YXB4o1", FileName:"rcloneTempvusudut0", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x39b28200, ext:63872250245, loc:(*time.Location)(0xc0001d8a80)}, UpdatedTime:api.Time{wall:0x0, ext:63872250246, loc:(*time.Location)(0xc0001d8a80)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsPutFiles Messages: Put 2025/01/12 03:44:26 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/01/12 03:44:26 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/01/12 03:44:27 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/01/12 03:44:27 ERROR : : error listing: directory not found 2025/01/12 03:44:28 DEBUG : pacer: Reducing sleep to 266.967773ms --- FAIL: TestIntegration (90.66s) --- FAIL: TestIntegration/FsMkdir (88.62s) --- PASS: TestIntegration/FsMkdir/FsEncoding (48.78s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (16.46s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (8.19s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (16.46s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (7.40s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles (37.05s) FAIL exit status 1 FAIL github.com/rclone/rclone/backend/pikpak 90.677s "go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|invalid_UTF-8|trailing_VT|trailing_dot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsDirMove|FsListDirFile2|FsListLevel2|FsListR|FsPurge|FsPurgeRoot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries)$'" - Finished ERROR in 1m32.462911675s (try 2/5): exit status 1: Failed [TestIntegration/FsMkdir/FsPutFiles]