"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|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 3/5) === RUN TestIntegration fstests.go:442: Using remote "TestPikPak:" 2024/11/07 04:24:13 DEBUG : Creating backend with remote "TestPikPak:rclone-test-jajehax8sini" 2024/11/07 04:24:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:711: testing "␍leading CR" 2024/11/07 04:24:15 DEBUG : ␍leading CR/␍leading CR: calculating gcid: failed to unwrap object from src: ␍leading CR/␍leading CR 2024/11/07 04:24:15 DEBUG : ␍leading CR/␍leading CR: gcid = 2c4ef80f17b6dbac55f98189441f1e135f1f0218 2024/11/07 04:24:20 DEBUG : ␍leading CR: Rmdir: contains trashed file: "␍leading CR" === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:711: testing "␊leading LF" 2024/11/07 04:24:22 DEBUG : ␊leading LF/␊leading LF: calculating gcid: failed to unwrap object from src: ␊leading LF/␊leading LF 2024/11/07 04:24:22 DEBUG : ␊leading LF/␊leading LF: gcid = d7b0b9408fc066c5a225eb436aa9ac1cb0769e07 2024/11/07 04:24:24 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:24:24 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/07 04:24:24 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:24:24 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/07 04:24:25 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:24:25 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/07 04:24:25 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:24:25 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/07 04:24:26 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:24:26 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/07 04:24:27 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/07 04:24:29 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/07 04:24:31 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/07 04:24:32 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/07 04:24:33 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/07 04:24:34 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/07 04:24:34 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/07 04:24:34 DEBUG : ␊leading LF: Rmdir: contains trashed file: "␊leading LF" 2024/11/07 04:24:35 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/07 04:24:35 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/07 04:24:36 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:711: testing "␉leading HT" 2024/11/07 04:24:36 DEBUG : pacer: Reducing sleep to 100ms 2024/11/07 04:24:36 DEBUG : ␉leading HT/␉leading HT: calculating gcid: failed to unwrap object from src: ␉leading HT/␉leading HT 2024/11/07 04:24:36 DEBUG : ␉leading HT/␉leading HT: gcid = 1bdbffe6983e67ae4585fc946c930eee7c59f2bf 2024/11/07 04:24:41 DEBUG : ␉leading HT: Rmdir: contains trashed file: "␉leading HT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:711: testing "␋leading VT" 2024/11/07 04:24:43 DEBUG : ␋leading VT/␋leading VT: calculating gcid: failed to unwrap object from src: ␋leading VT/␋leading VT 2024/11/07 04:24:43 DEBUG : ␋leading VT/␋leading VT: gcid = 19670fd5e5d5dd068545de038c7a5d641e743750 2024/11/07 04:24:47 DEBUG : ␋leading VT: Rmdir: contains trashed file: "␋leading VT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:711: testing ".leading dot" 2024/11/07 04:24:49 DEBUG : .leading dot/.leading dot: calculating gcid: failed to unwrap object from src: .leading dot/.leading dot 2024/11/07 04:24:49 DEBUG : .leading dot/.leading dot: gcid = 46071fbc973a469a6ee8ea89d26b4db4200df1d6 2024/11/07 04:24:51 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:24:51 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/07 04:24:51 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:24:51 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/07 04:24:52 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:24:52 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/07 04:24:52 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:24:52 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/07 04:24:53 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:24:53 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/07 04:24:54 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/07 04:24:56 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/07 04:24:58 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/07 04:24:59 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/07 04:25:00 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/07 04:25:01 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/07 04:25:01 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/07 04:25:01 DEBUG : .leading dot: Rmdir: contains trashed file: ".leading dot" 2024/11/07 04:25:01 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/07 04:25:02 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/07 04:25:02 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:711: testing "trailing space " 2024/11/07 04:25:03 DEBUG : pacer: Reducing sleep to 100ms 2024/11/07 04:25:03 DEBUG : trailing space /trailing space : calculating gcid: failed to unwrap object from src: trailing space /trailing space 2024/11/07 04:25:03 DEBUG : trailing space /trailing space : gcid = 2b0950fdb41f0573b4e28980b45eff96be393886 2024/11/07 04:25:06 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:06 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/07 04:25:06 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:06 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/07 04:25:06 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:06 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/07 04:25:07 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:07 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/07 04:25:07 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:07 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/07 04:25:09 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:11 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/07 04:25:13 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/07 04:25:15 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/07 04:25:16 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/07 04:25:17 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/07 04:25:17 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/07 04:25:18 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/07 04:25:18 DEBUG : trailing space : Rmdir: contains trashed file: "trailing space " 2024/11/07 04:25:18 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/07 04:25:19 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/07 04:25:19 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:711: testing "trailing CR␍" 2024/11/07 04:25:19 DEBUG : pacer: Reducing sleep to 100ms 2024/11/07 04:25:20 DEBUG : trailing CR␍/trailing CR␍: calculating gcid: failed to unwrap object from src: trailing CR␍/trailing CR␍ 2024/11/07 04:25:20 DEBUG : trailing CR␍/trailing CR␍: gcid = 784725f567917dba12b6a8f76d66b81fce9ad444 2024/11/07 04:25:22 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:22 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/07 04:25:23 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:23 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/07 04:25:23 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:23 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/07 04:25:23 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:23 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/07 04:25:24 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:24 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/07 04:25:26 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/07 04:25:28 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/07 04:25:29 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/07 04:25:30 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/07 04:25:31 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/07 04:25:32 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/07 04:25:32 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/07 04:25:32 DEBUG : trailing CR␍: Rmdir: contains trashed file: "trailing CR␍" 2024/11/07 04:25:33 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/07 04:25:34 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/07 04:25:34 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:711: testing "trailing LF␊" 2024/11/07 04:25:34 DEBUG : pacer: Reducing sleep to 100ms 2024/11/07 04:25:35 DEBUG : trailing LF␊/trailing LF␊: calculating gcid: failed to unwrap object from src: trailing LF␊/trailing LF␊ 2024/11/07 04:25:35 DEBUG : trailing LF␊/trailing LF␊: gcid = e3de5b30f806f77c00fce859b8308372d02ccf32 2024/11/07 04:25:37 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:37 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/07 04:25:37 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:37 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/07 04:25:37 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:37 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/07 04:25:38 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:38 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/07 04:25:39 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:39 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/07 04:25:40 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:25:42 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/07 04:25:44 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/07 04:25:46 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/07 04:25:47 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/07 04:25:48 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/07 04:25:48 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/07 04:25:49 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/07 04:25:49 DEBUG : trailing LF␊: Rmdir: contains trashed file: "trailing LF␊" 2024/11/07 04:25:49 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/07 04:25:50 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/07 04:25:50 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:711: testing "trailing HT␉" 2024/11/07 04:25:51 DEBUG : pacer: Reducing sleep to 100ms 2024/11/07 04:25:51 DEBUG : trailing HT␉/trailing HT␉: calculating gcid: failed to unwrap object from src: trailing HT␉/trailing HT␉ 2024/11/07 04:25:51 DEBUG : trailing HT␉/trailing HT␉: gcid = 3ee1548c22ac44b3461076443274104b0c384e6e 2024/11/07 04:25:55 DEBUG : trailing HT␉: Rmdir: contains trashed file: "trailing HT␉" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:711: testing "trailing VT␋" 2024/11/07 04:25:58 DEBUG : trailing VT␋/trailing VT␋: calculating gcid: failed to unwrap object from src: trailing VT␋/trailing VT␋ 2024/11/07 04:25:58 DEBUG : trailing VT␋/trailing VT␋: gcid = 970a34f3769632795d15ab38530172314aaea95b 2024/11/07 04:26:00 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:00 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/07 04:26:00 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:00 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/07 04:26:01 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:01 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/07 04:26:01 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:01 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/07 04:26:02 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:02 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/07 04:26:03 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:05 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/07 04:26:07 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/07 04:26:09 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/07 04:26:10 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/07 04:26:11 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/07 04:26:12 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/07 04:26:12 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/07 04:26:12 DEBUG : trailing VT␋: Rmdir: contains trashed file: "trailing VT␋" 2024/11/07 04:26:13 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/07 04:26:13 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/07 04:26:14 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:711: testing "trailing dot." 2024/11/07 04:26:14 DEBUG : pacer: Reducing sleep to 100ms 2024/11/07 04:26:14 DEBUG : trailing dot./trailing dot.: calculating gcid: failed to unwrap object from src: trailing dot./trailing dot. 2024/11/07 04:26:14 DEBUG : trailing dot./trailing dot.: gcid = 6a0bafa00f092522700277e2ca6be6ce0c42047f 2024/11/07 04:26:17 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:17 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/07 04:26:17 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:17 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/07 04:26:17 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:17 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/07 04:26:18 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:18 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/07 04:26:19 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:19 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/07 04:26:20 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:22 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/07 04:26:25 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2024/11/07 04:26:25 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/07 04:26:26 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/07 04:26:27 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/07 04:26:28 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/07 04:26:28 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/07 04:26:29 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/07 04:26:29 DEBUG : trailing dot.: Rmdir: contains trashed file: "trailing dot." 2024/11/07 04:26:29 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/07 04:26:30 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/07 04:26:30 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:711: testing "invalid utf-8\xfe" 2024/11/07 04:26:30 DEBUG : pacer: Reducing sleep to 100ms 2024/11/07 04:26:31 DEBUG : invalid utf-8/invalid utf-8: calculating gcid: failed to unwrap object from src: invalid utf-8/invalid utf-8 2024/11/07 04:26:31 DEBUG : invalid utf-8/invalid utf-8: gcid = fe8cf2e6c3f135ee42e23168f2cd094039cc0e4a 2024/11/07 04:26:33 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:33 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/07 04:26:33 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:33 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/07 04:26:34 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:34 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/07 04:26:34 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:34 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/07 04:26:35 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:35 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/07 04:26:36 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/07 04:26:38 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/07 04:26:40 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/07 04:26:41 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/07 04:26:42 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/07 04:26:43 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/07 04:26:43 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/07 04:26:43 DEBUG : invalid utf-8: Rmdir: contains trashed file: "invalid utf-8\xfe" 2024/11/07 04:26:43 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/07 04:26:44 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/07 04:26:44 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:711: testing "test%46.txt" 2024/11/07 04:26:45 DEBUG : pacer: Reducing sleep to 100ms 2024/11/07 04:26:45 DEBUG : test%46.txt/test%46.txt: calculating gcid: failed to unwrap object from src: test%46.txt/test%46.txt 2024/11/07 04:26:45 DEBUG : test%46.txt/test%46.txt: gcid = 40409ebcd0619bc8f9e496ef55c8372468ea1d9e 2024/11/07 04:26:47 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:47 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/07 04:26:48 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:48 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/07 04:26:48 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:48 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/07 04:26:48 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:48 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/07 04:26:49 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:49 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/07 04:26:51 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:26:53 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/07 04:26:55 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/07 04:26:56 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/07 04:26:57 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/07 04:26:58 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/07 04:26:59 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/07 04:26:59 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/07 04:26:59 DEBUG : test%46.txt: Rmdir: contains trashed file: "test%46.txt" 2024/11/07 04:27:00 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/07 04:27:00 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/07 04:27:01 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsPutFiles 2024/11/07 04:27:01 DEBUG : pacer: Reducing sleep to 100ms 2024/11/07 04:27:01 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2024/11/07 04:27:01 DEBUG : file name.txt: gcid = f44a26165abf1d3d4f9f94d99f966d30f24396a1 2024/11/07 04:27:03 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:03 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/07 04:27:03 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:03 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/07 04:27:04 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:04 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/07 04:27:04 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:04 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/07 04:27:05 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:05 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/07 04:27:07 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:09 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:11 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/07 04:27:13 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/07 04:27:14 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/07 04:27:15 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/07 04:27:16 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/07 04:27:17 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/07 04:27:17 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/07 04:27:17 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/07 04:27:18 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/07 04:27:18 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/11/07 04:27:18 DEBUG : pacer: Reducing sleep to 100ms 2024/11/07 04:27:18 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: calculating gcid: failed to unwrap object from src: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt 2024/11/07 04:27:18 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: gcid = 36934c80f52434775e9acb85a617c53d264ea9d9 2024/11/07 04:27:20 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:20 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/07 04:27:21 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:21 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/07 04:27:21 DEBUG : pacer: Reducing sleep to 300ms 2024/11/07 04:27:21 DEBUG : pacer: Reducing sleep to 225ms 2024/11/07 04:27:22 DEBUG : pacer: Reducing sleep to 168.75ms 2024/11/07 04:27:22 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2024/11/07 04:27:22 DEBUG : file name.txt: gcid = 42679447ded5a623b459ee01647b25ad88c0af7a 2024/11/07 04:27:22 DEBUG : pacer: Reducing sleep to 126.5625ms 2024/11/07 04:27:24 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:24 DEBUG : pacer: Rate limited, increasing sleep to 253.125ms 2024/11/07 04:27:24 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:24 DEBUG : pacer: Rate limited, increasing sleep to 506.25ms 2024/11/07 04:27:24 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:24 DEBUG : pacer: Rate limited, increasing sleep to 1.0125s 2024/11/07 04:27:25 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:25 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/07 04:27:26 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:28 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/07 04:27:30 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/07 04:27:31 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/07 04:27:32 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/07 04:27:33 DEBUG : pacer: Reducing sleep to 474.609375ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2024/11/07 04:27:34 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/07 04:27:34 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/07 04:27:35 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/07 04:27:35 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/07 04:27:35 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2024/11/07 04:27:35 DEBUG : pacer: Reducing sleep to 100ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2024/11/07 04:27:38 DEBUG : dirToPurge/fileToPurge.txt: calculating gcid: failed to unwrap object from src: dirToPurge/fileToPurge.txt 2024/11/07 04:27:38 DEBUG : dirToPurge/fileToPurge.txt: gcid = be29e38b3fdfcfda9d0ece8b4fc6c21cca2161f1 2024/11/07 04:27:40 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:40 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/07 04:27:40 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:40 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/07 04:27:41 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:41 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/07 04:27:41 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/07 04:27:41 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/07 04:27:42 DEBUG : pacer: Reducing sleep to 1.2s 2024/11/07 04:27:44 DEBUG : pacer: Reducing sleep to 900ms 2024/11/07 04:27:45 DEBUG : pacer: Reducing sleep to 675ms 2024/11/07 04:27:46 DEBUG : pacer: Reducing sleep to 506.25ms 2024/11/07 04:27:46 DEBUG : pacer: Reducing sleep to 379.6875ms 2024/11/07 04:27:47 DEBUG : pacer: Reducing sleep to 284.765625ms 2024/11/07 04:27:47 DEBUG : pacer: Reducing sleep to 213.574218ms 2024/11/07 04:27:48 DEBUG : pacer: Reducing sleep to 160.180663ms 2024/11/07 04:27:48 DEBUG : pacer: Reducing sleep to 120.135497ms 2024/11/07 04:27:49 DEBUG : pacer: Reducing sleep to 100ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2024/11/07 04:27:51 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: calculating gcid: failed to unwrap object from src: dirToPurgeFromRoot/fileToPurgeFromRoot.txt 2024/11/07 04:27:51 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: gcid = b476b3f63877200401edd46f2d9a9014723294d4 2024/11/07 04:27:55 DEBUG : Creating backend with remote "TestPikPak:rclone-test-jajehax8sini/dirToPurgeFromRoot" === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2024/11/07 04:27:58 DEBUG : Creating backend with remote "TestPikPak:rclone-test-golefok4vizo" 2024/11/07 04:28:08 DEBUG : PikPak root 'rclone-test-golefok4vizo': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1915: Opening root remote "TestPikPak:" path "rclone-test-jajehax8sini" from "TestPikPak:rclone-test-jajehax8sini" 2024/11/07 04:28:09 DEBUG : Creating backend with remote "TestPikPak:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2024/11/07 04:28:13 ERROR : : error listing: directory not found --- PASS: TestIntegration (240.40s) --- PASS: TestIntegration/FsMkdir (239.06s) --- PASS: TestIntegration/FsMkdir/FsEncoding (166.31s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (6.63s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (14.31s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (6.36s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (6.31s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (14.23s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (16.73s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (14.76s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (16.41s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (6.35s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (16.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (16.54s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (14.25s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (16.23s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (70.97s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (2.06s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (1.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.57s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (13.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (7.83s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (10.95s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (2.75s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (2.45s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.30s) PASS ok github.com/rclone/rclone/backend/pikpak 240.410s "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|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 OK in 4m1.487311263s (try 3/5)