"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|invalid_UTF-8|leading_CR|leading_HT|leading_LF|leading_VT|leading_dot|leading_space|leading_tilde|trailing_CR|trailing_HT|trailing_LF|trailing_VT|trailing_dot|trailing_space)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsDirMove|FsListDirFile2|FsListLevel2|FsListR|FsPurge|FsPurgeRoot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries)$'" - Starting (try 2/5) === RUN TestIntegration fstests.go:438: Using remote "TestPikPak:" 2026/05/09 01:25:07 DEBUG : Creating backend with remote "TestPikPak:rclone-test-bixunuw8ximi" 2026/05/09 01:25:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:708: testing " leading space" 2026/05/09 01:25:11 DEBUG : leading space/ leading space: calculating gcid: failed to unwrap object from src: leading space/ leading space 2026/05/09 01:25:11 DEBUG : leading space/ leading space: gcid = 7d53a6deb02722ce15c25c07a0adffded2a9c2b9 2026/05/09 01:25:15 DEBUG : leading space: Rmdir: contains trashed file: " leading space" === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:708: testing "~leading tilde" 2026/05/09 01:25:17 DEBUG : ~leading tilde/~leading tilde: calculating gcid: failed to unwrap object from src: ~leading tilde/~leading tilde 2026/05/09 01:25:17 DEBUG : ~leading tilde/~leading tilde: gcid = a93323ca3eb028a7727447cc36a4a6ac10ea2c4d 2026/05/09 01:25:22 DEBUG : ~leading tilde: Rmdir: contains trashed file: "~leading tilde" === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:708: testing "␍leading CR" 2026/05/09 01:25:24 DEBUG : ␍leading CR/␍leading CR: calculating gcid: failed to unwrap object from src: ␍leading CR/␍leading CR 2026/05/09 01:25:24 DEBUG : ␍leading CR/␍leading CR: gcid = a5029ca268e249e1527282a0e9e810d397c2a8b2 2026/05/09 01:25:27 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2026/05/09 01:25:27 DEBUG : Config file has changed externally - reloading 2026/05/09 01:25:30 DEBUG : ␍leading CR: Rmdir: contains trashed file: "␍leading CR" === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:708: testing "␊leading LF" 2026/05/09 01:25:32 DEBUG : ␊leading LF/␊leading LF: calculating gcid: failed to unwrap object from src: ␊leading LF/␊leading LF 2026/05/09 01:25:32 DEBUG : ␊leading LF/␊leading LF: gcid = 76d1e31b03fd98b8fdda3700a44552aa0c2a8178 2026/05/09 01:25:37 DEBUG : ␊leading LF: Rmdir: contains trashed file: "␊leading LF" === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:708: testing "␉leading HT" 2026/05/09 01:25:39 DEBUG : ␉leading HT/␉leading HT: calculating gcid: failed to unwrap object from src: ␉leading HT/␉leading HT 2026/05/09 01:25:39 DEBUG : ␉leading HT/␉leading HT: gcid = d696b8ac8066c852a961eb819425d2fd01e90e00 2026/05/09 01:25:44 DEBUG : ␉leading HT: Rmdir: contains trashed file: "␉leading HT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:708: testing "␋leading VT" 2026/05/09 01:25:46 DEBUG : ␋leading VT/␋leading VT: calculating gcid: failed to unwrap object from src: ␋leading VT/␋leading VT 2026/05/09 01:25:46 DEBUG : ␋leading VT/␋leading VT: gcid = e9a819bfd949d2100e53c6711e2ffb3a9ddcaf93 2026/05/09 01:25:48 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:25:48 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2026/05/09 01:25:48 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:25:48 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/05/09 01:25:49 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:25:49 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/05/09 01:25:49 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:25:49 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/05/09 01:25:50 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:25:50 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/09 01:25:52 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/09 01:25:54 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/09 01:25:55 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/09 01:25:56 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/09 01:25:57 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/09 01:25:58 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/09 01:25:58 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/09 01:25:58 DEBUG : ␋leading VT: Rmdir: contains trashed file: "␋leading VT" 2026/05/09 01:25:59 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/09 01:25:59 DEBUG : pacer: Reducing sleep to 150.169371ms 2026/05/09 01:26:00 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:708: testing ".leading dot" 2026/05/09 01:26:00 DEBUG : pacer: Reducing sleep to 100ms 2026/05/09 01:26:01 DEBUG : .leading dot/.leading dot: calculating gcid: failed to unwrap object from src: .leading dot/.leading dot 2026/05/09 01:26:01 DEBUG : .leading dot/.leading dot: gcid = 2ec3abecebc5714e6077021af59596b1a428fa6b 2026/05/09 01:26:03 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:26:03 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2026/05/09 01:26:03 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:26:03 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/05/09 01:26:04 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:26:04 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/05/09 01:26:04 DEBUG : pacer: Reducing sleep to 600ms 2026/05/09 01:26:05 DEBUG : pacer: Reducing sleep to 450ms 2026/05/09 01:26:05 DEBUG : pacer: Reducing sleep to 337.5ms 2026/05/09 01:26:06 DEBUG : pacer: Reducing sleep to 253.125ms 2026/05/09 01:26:06 DEBUG : pacer: Reducing sleep to 189.84375ms 2026/05/09 01:26:07 DEBUG : pacer: Reducing sleep to 142.382812ms 2026/05/09 01:26:07 DEBUG : pacer: Reducing sleep to 106.787109ms 2026/05/09 01:26:07 DEBUG : .leading dot: Rmdir: contains trashed file: ".leading dot" 2026/05/09 01:26:08 DEBUG : pacer: Reducing sleep to 100ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:708: testing "trailing space " 2026/05/09 01:26:10 DEBUG : trailing space /trailing space : calculating gcid: failed to unwrap object from src: trailing space /trailing space 2026/05/09 01:26:10 DEBUG : trailing space /trailing space : gcid = 7467a16643bf48d55c759a47cfd782f71c5a4fbc 2026/05/09 01:26:14 DEBUG : trailing space : Rmdir: contains trashed file: "trailing space " === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:708: testing "trailing CR␍" 2026/05/09 01:26:16 DEBUG : trailing CR␍/trailing CR␍: calculating gcid: failed to unwrap object from src: trailing CR␍/trailing CR␍ 2026/05/09 01:26:16 DEBUG : trailing CR␍/trailing CR␍: gcid = c6fc0144e5690c380a353020ea6eaaefcf51ad22 2026/05/09 01:26:21 DEBUG : trailing CR␍: Rmdir: contains trashed file: "trailing CR␍" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:708: testing "trailing LF␊" 2026/05/09 01:26:23 DEBUG : trailing LF␊/trailing LF␊: calculating gcid: failed to unwrap object from src: trailing LF␊/trailing LF␊ 2026/05/09 01:26:23 DEBUG : trailing LF␊/trailing LF␊: gcid = 543d61a1e65bba628b07798c4c50cd0d0f0dee75 2026/05/09 01:26:28 DEBUG : trailing LF␊: Rmdir: contains trashed file: "trailing LF␊" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:708: testing "trailing HT␉" 2026/05/09 01:26:30 DEBUG : trailing HT␉/trailing HT␉: calculating gcid: failed to unwrap object from src: trailing HT␉/trailing HT␉ 2026/05/09 01:26:30 DEBUG : trailing HT␉/trailing HT␉: gcid = 175502eef119b4874e553c83c2b2c395ca7a3db7 2026/05/09 01:26:35 DEBUG : trailing HT␉: Rmdir: contains trashed file: "trailing HT␉" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:708: testing "trailing VT␋" 2026/05/09 01:26:37 DEBUG : trailing VT␋/trailing VT␋: calculating gcid: failed to unwrap object from src: trailing VT␋/trailing VT␋ 2026/05/09 01:26:37 DEBUG : trailing VT␋/trailing VT␋: gcid = ea480347f9cc09a88033ce866bc087d49184cb02 2026/05/09 01:26:42 DEBUG : trailing VT␋: Rmdir: contains trashed file: "trailing VT␋" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:708: testing "trailing dot." 2026/05/09 01:26:44 DEBUG : trailing dot./trailing dot.: calculating gcid: failed to unwrap object from src: trailing dot./trailing dot. 2026/05/09 01:26:44 DEBUG : trailing dot./trailing dot.: gcid = 8f1772d75a249470b016afefa96e9d81238f3db0 2026/05/09 01:26:48 DEBUG : trailing dot.: Rmdir: contains trashed file: "trailing dot." === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:708: testing "invalid utf-8\xfe" 2026/05/09 01:26:51 DEBUG : invalid utf-8/invalid utf-8: calculating gcid: failed to unwrap object from src: invalid utf-8/invalid utf-8 2026/05/09 01:26:51 DEBUG : invalid utf-8/invalid utf-8: gcid = 06543f2800f76f775a7650a655fc5c3e6ed3cbc3 2026/05/09 01:26:53 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:26:53 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2026/05/09 01:26:53 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:26:53 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/05/09 01:26:54 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:26:54 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/05/09 01:26:54 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:26:54 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/05/09 01:26:55 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:26:55 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/09 01:26:56 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/09 01:26:58 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/09 01:27:00 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/09 01:27:01 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/09 01:27:02 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/09 01:27:03 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/09 01:27:03 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/09 01:27:03 DEBUG : invalid utf-8: Rmdir: contains trashed file: "invalid utf-8\xfe" 2026/05/09 01:27:03 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/09 01:27:04 DEBUG : pacer: Reducing sleep to 150.169371ms 2026/05/09 01:27:05 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:708: testing "test%46.txt" 2026/05/09 01:27:05 DEBUG : pacer: Reducing sleep to 100ms 2026/05/09 01:27:06 DEBUG : test%46.txt/test%46.txt: calculating gcid: failed to unwrap object from src: test%46.txt/test%46.txt 2026/05/09 01:27:06 DEBUG : test%46.txt/test%46.txt: gcid = 0a7eed24f978e48aecae9971c5bb57a36d89cfa3 2026/05/09 01:27:08 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:08 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2026/05/09 01:27:08 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:08 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/05/09 01:27:09 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:09 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/05/09 01:27:09 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:09 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/05/09 01:27:10 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:10 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/09 01:27:11 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/09 01:27:13 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/09 01:27:15 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/09 01:27:16 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/09 01:27:17 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/09 01:27:18 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/09 01:27:18 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/09 01:27:18 DEBUG : test%46.txt: Rmdir: contains trashed file: "test%46.txt" 2026/05/09 01:27:18 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/09 01:27:19 DEBUG : pacer: Reducing sleep to 150.169371ms 2026/05/09 01:27:19 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsPutFiles 2026/05/09 01:27:20 DEBUG : pacer: Reducing sleep to 100ms 2026/05/09 01:27:20 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2026/05/09 01:27:20 DEBUG : file name.txt: gcid = 8e3efc4a38fac223e5e3a37e24cd3e9784cef3f2 2026/05/09 01:27:25 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: calculating gcid: failed to unwrap object from src: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt 2026/05/09 01:27:25 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: gcid = a4681ae8276c4dd38182248945073c84b79cf747 2026/05/09 01:27:28 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:28 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2026/05/09 01:27:28 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:28 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/05/09 01:27:28 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:28 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/05/09 01:27:28 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:28 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/05/09 01:27:29 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:29 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/09 01:27:31 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/09 01:27:33 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/09 01:27:34 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/09 01:27:34 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2026/05/09 01:27:34 DEBUG : file name.txt: gcid = 087805302d3431efc05a4cba85f9d6f86d130716 2026/05/09 01:27:36 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/09 01:27:37 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/09 01:27:38 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/09 01:27:39 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/09 01:27:39 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/09 01:27:40 DEBUG : pacer: Reducing sleep to 150.169371ms 2026/05/09 01:27:40 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/05/09 01:27:40 DEBUG : pacer: Reducing sleep to 100ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/05/09 01:27:44 DEBUG : dirToPurge/fileToPurge.txt: calculating gcid: failed to unwrap object from src: dirToPurge/fileToPurge.txt 2026/05/09 01:27:44 DEBUG : dirToPurge/fileToPurge.txt: gcid = f0ea6f0572acb4205efe87ca87569a43bd52d69c 2026/05/09 01:27:47 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:47 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2026/05/09 01:27:47 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:47 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/05/09 01:27:47 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:47 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/05/09 01:27:48 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:48 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/05/09 01:27:48 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:48 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/09 01:27:50 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:52 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:54 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:56 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:58 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:27:58 DEBUG : fileToPurge.txt: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOs9LEu8-Oxriql1yeRsX6LQo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x233186d83e80), FileID:"VOs9LEtt-Oxriql1yeRsX6LMo2", FileName:"fileToPurge.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1f78a40, ext:63913886865, loc:(*time.Location)(0x233186d9eee0)}, UpdatedTime:api.Time{wall:0x38444c0, ext:63913886865, loc:(*time.Location)(0x233186d9eee0)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/09 01:28:00 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/09 01:28:02 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/09 01:28:04 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/09 01:28:04 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:"VOs9LEu8-Oxriql1yeRsX6LQo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x233186d83e80), FileID:"VOs9LEtt-Oxriql1yeRsX6LMo2", FileName:"fileToPurge.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1f78a40, ext:63913886865, loc:(*time.Location)(0x233186d9eee0)}, UpdatedTime:api.Time{wall:0x38444c0, ext:63913886865, loc:(*time.Location)(0x233186d9eee0)}, 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:28:05 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/09 01:28:06 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/09 01:28:06 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/09 01:28:06 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: calculating gcid: failed to unwrap object from src: dirToPurgeFromRoot/fileToPurgeFromRoot.txt 2026/05/09 01:28:06 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: gcid = 3143e2e00433628bc348f6ea457fd7bb14aaa08e 2026/05/09 01:28:07 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/09 01:28:08 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/09 01:28:09 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:28:09 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/09 01:28:09 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:28:09 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/09 01:28:09 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:28:09 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/09 01:28:10 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/09 01:28:10 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/09 01:28:12 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/09 01:28:14 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/09 01:28:15 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/09 01:28:17 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/09 01:28:17 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/09 01:28:18 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/09 01:28:18 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/09 01:28:19 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/09 01:28:19 DEBUG : pacer: Reducing sleep to 150.169371ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/05/09 01:28:20 DEBUG : pacer: Reducing sleep to 112.627028ms 2026/05/09 01:28:21 DEBUG : pacer: Reducing sleep to 100ms 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:1205 Error: Not equal: expected: []string{"dirToPurgeFromRoot", "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,3 @@ -([]string) (len=5) { +([]string) (len=6) { + (string) (len=10) "dirToPurge", (string) (len=18) "dirToPurgeFromRoot", Test: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot Messages: directories 2026/05/09 01:28:30 DEBUG : Creating backend with remote "TestPikPak:rclone-test-bixunuw8ximi/dirToPurgeFromRoot" 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:1222 Error: Not equal: expected: []string{"hello? sausage", "hello? sausage/êé", "hello? sausage/êé/Hello, 世界", "hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} actual : []string{"dirToPurge", "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=10) "dirToPurge", (string) (len=14) "hello? sausage", Test: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot Messages: directories === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/05/09 01:28:45 DEBUG : Creating backend with remote "TestPikPak:rclone-test-jokiyah2jufa" 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/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=32) "new_name/sub_new_name/dirToPurge", (string) (len=36) "new_name/sub_new_name/hello? sausage", Test: TestIntegration/FsMkdir/FsPutFiles/FsDirMove Messages: directories 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", "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=10) "dirToPurge", (string) (len=14) "hello? sausage", Test: TestIntegration/FsMkdir/FsPutFiles/FsDirMove Messages: directories 2026/05/09 01:29:15 DEBUG : PikPak root 'rclone-test-jokiyah2jufa': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1920: Opening root remote "TestPikPak:" path "rclone-test-bixunuw8ximi" from "TestPikPak:rclone-test-bixunuw8ximi" 2026/05/09 01:29:16 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-bixunuw8ximi/hello? sausage", "rclone-test-bixunuw8ximi/hello? sausage/êé", "rclone-test-bixunuw8ximi/hello? sausage/êé/Hello, 世界", "rclone-test-bixunuw8ximi/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} actual : []string{"rclone-test-bixunuw8ximi/dirToPurge", "rclone-test-bixunuw8ximi/hello? sausage", "rclone-test-bixunuw8ximi/hello? sausage/êé", "rclone-test-bixunuw8ximi/hello? sausage/êé/Hello, 世界", "rclone-test-bixunuw8ximi/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=4) { +([]string) (len=5) { + (string) (len=35) "rclone-test-bixunuw8ximi/dirToPurge", (string) (len=39) "rclone-test-bixunuw8ximi/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-bixunuw8ximi/hello? sausage"} actual : []string{"rclone-test-bixunuw8ximi/dirToPurge", "rclone-test-bixunuw8ximi/hello? sausage"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=35) "rclone-test-bixunuw8ximi/dirToPurge", (string) (len=39) "rclone-test-bixunuw8ximi/hello? sausage" Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries Messages: directories not equal 2026/05/09 01:29:32 ERROR : error listing: directory not found --- FAIL: TestIntegration (265.14s) --- FAIL: TestIntegration/FsMkdir (262.70s) --- PASS: TestIntegration/FsMkdir/FsEncoding (130.07s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (6.82s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (6.88s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (7.96s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (6.88s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (6.73s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (14.79s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (8.91s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (6.83s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (6.82s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (6.88s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (6.83s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (6.84s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (6.81s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (14.86s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (14.96s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles (130.44s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (1.47s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (1.43s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.59s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsPurge (20.58s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (40.55s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (31.77s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot (13.55s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (12.96s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.58s) FAIL exit status 1 FAIL github.com/rclone/rclone/backend/pikpak 265.157s "go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|invalid_UTF-8|leading_CR|leading_HT|leading_LF|leading_VT|leading_dot|leading_space|leading_tilde|trailing_CR|trailing_HT|trailing_LF|trailing_VT|trailing_dot|trailing_space)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsDirMove|FsListDirFile2|FsListLevel2|FsListR|FsPurge|FsPurgeRoot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries)$'" - Finished ERROR in 4m26.101891452s (try 2/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]