"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|dot_dot|invalid_UTF-8|leading_CR|leading_HT|leading_LF|leading_VT|leading_dot|leading_space|leading_tilde|punctuation|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 4/5)
=== RUN   TestIntegration
    fstests.go:442: Using remote "TestPikPak:"
2024/11/15 05:19:24 DEBUG : Creating backend with remote "TestPikPak:rclone-test-qodiheq8kumu"
2024/11/15 05:19:24 DEBUG : Using config file from "/home/rclone/.rclone.conf"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsEncoding
=== RUN   TestIntegration/FsMkdir/FsEncoding/dot_dot
    fstests.go:711: testing "．．"
2024/11/15 05:19:28 DEBUG : ．．/．．: calculating gcid: failed to unwrap object from src: ．．/．．
2024/11/15 05:19:28 DEBUG : ．．/．．: gcid = 2b50d58c8f3f7b94defbd6b6d9bf42f36852dd44
2024/11/15 05:19:30 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:19:30 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/11/15 05:19:30 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:19:30 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/11/15 05:19:31 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:19:31 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/11/15 05:19:31 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:19:31 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/11/15 05:19:32 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:19:32 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/11/15 05:19:33 DEBUG : pacer: Reducing sleep to 1.5s
2024/11/15 05:19:35 DEBUG : pacer: Reducing sleep to 1.125s
2024/11/15 05:19:37 DEBUG : pacer: Reducing sleep to 843.75ms
2024/11/15 05:19:38 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/11/15 05:19:39 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/11/15 05:19:40 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/11/15 05:19:40 DEBUG : pacer: Reducing sleep to 266.967773ms
2024/11/15 05:19:40 DEBUG : ．．: Rmdir: contains trashed file: "．．"
2024/11/15 05:19:41 DEBUG : pacer: Reducing sleep to 200.225829ms
2024/11/15 05:19:41 DEBUG : pacer: Reducing sleep to 150.169371ms
2024/11/15 05:19:42 DEBUG : pacer: Reducing sleep to 112.627028ms
=== RUN   TestIntegration/FsMkdir/FsEncoding/punctuation
    fstests.go:711: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"
2024/11/15 05:19:42 DEBUG : pacer: Reducing sleep to 100ms
2024/11/15 05:19:43 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: calculating gcid: failed to unwrap object from src: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~
2024/11/15 05:19:43 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: gcid = 6e39712404e2cb027e900485ab8e5359c2492d08
2024/11/15 05:19:47 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Rmdir: contains trashed file: "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_space
    fstests.go:711: testing " leading space"
2024/11/15 05:19:49 DEBUG :  leading space/ leading space: calculating gcid: failed to unwrap object from src:  leading space/ leading space
2024/11/15 05:19:49 DEBUG :  leading space/ leading space: gcid = e3232eb438c06159b85b5ab75118126d92ff6b62
2024/11/15 05:19:51 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:19:51 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/11/15 05:19:52 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:19:52 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/11/15 05:19:52 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:19:52 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/11/15 05:19:52 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:19:52 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/11/15 05:19:53 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:19:53 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/11/15 05:19:55 DEBUG : pacer: Reducing sleep to 1.5s
2024/11/15 05:19:57 DEBUG : pacer: Reducing sleep to 1.125s
2024/11/15 05:19:58 DEBUG : pacer: Reducing sleep to 843.75ms
2024/11/15 05:19:59 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/11/15 05:20:00 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/11/15 05:20:01 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/11/15 05:20:01 DEBUG : pacer: Reducing sleep to 266.967773ms
2024/11/15 05:20:01 DEBUG :  leading space: Rmdir: contains trashed file: " leading space"
2024/11/15 05:20:02 DEBUG : pacer: Reducing sleep to 200.225829ms
2024/11/15 05:20:02 DEBUG : pacer: Reducing sleep to 150.169371ms
2024/11/15 05:20:03 DEBUG : pacer: Reducing sleep to 112.627028ms
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_tilde
    fstests.go:711: testing "~leading tilde"
2024/11/15 05:20:03 DEBUG : pacer: Reducing sleep to 100ms
2024/11/15 05:20:04 DEBUG : ~leading tilde/~leading tilde: calculating gcid: failed to unwrap object from src: ~leading tilde/~leading tilde
2024/11/15 05:20:04 DEBUG : ~leading tilde/~leading tilde: gcid = 18b60361cb9f53557193a1ba6a76e6c0d5a4becc
2024/11/15 05:20:08 DEBUG : ~leading tilde: Rmdir: contains trashed file: "~leading tilde"
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_CR
    fstests.go:711: testing "␍leading CR"
2024/11/15 05:20:10 DEBUG : ␍leading CR/␍leading CR: calculating gcid: failed to unwrap object from src: ␍leading CR/␍leading CR
2024/11/15 05:20:10 DEBUG : ␍leading CR/␍leading CR: gcid = 4ca85ab525f50c683802eab95125375b2dec7389
2024/11/15 05:20:12 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:12 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/11/15 05:20:12 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:12 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/11/15 05:20:13 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:13 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/11/15 05:20:13 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:13 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/11/15 05:20:14 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:14 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/11/15 05:20:16 DEBUG : pacer: Reducing sleep to 1.5s
2024/11/15 05:20:18 DEBUG : pacer: Reducing sleep to 1.125s
2024/11/15 05:20:19 DEBUG : pacer: Reducing sleep to 843.75ms
2024/11/15 05:20:20 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/11/15 05:20:21 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/11/15 05:20:22 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/11/15 05:20:22 DEBUG : pacer: Reducing sleep to 266.967773ms
2024/11/15 05:20:22 DEBUG : ␍leading CR: Rmdir: contains trashed file: "␍leading CR"
2024/11/15 05:20:23 DEBUG : pacer: Reducing sleep to 200.225829ms
2024/11/15 05:20:23 DEBUG : pacer: Reducing sleep to 150.169371ms
2024/11/15 05:20:24 DEBUG : pacer: Reducing sleep to 112.627028ms
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_LF
    fstests.go:711: testing "␊leading LF"
2024/11/15 05:20:24 DEBUG : pacer: Reducing sleep to 100ms
2024/11/15 05:20:24 DEBUG : ␊leading LF/␊leading LF: calculating gcid: failed to unwrap object from src: ␊leading LF/␊leading LF
2024/11/15 05:20:24 DEBUG : ␊leading LF/␊leading LF: gcid = 8a72876038f067e5474c57049399245f220a5419
2024/11/15 05:20:27 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:27 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/11/15 05:20:27 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:27 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/11/15 05:20:27 DEBUG : pacer: Reducing sleep to 300ms
2024/11/15 05:20:28 DEBUG : pacer: Reducing sleep to 225ms
2024/11/15 05:20:29 DEBUG : pacer: Reducing sleep to 168.75ms
2024/11/15 05:20:29 DEBUG : pacer: Reducing sleep to 126.5625ms
2024/11/15 05:20:29 DEBUG : pacer: Reducing sleep to 100ms
2024/11/15 05:20:30 DEBUG : ␊leading LF: Rmdir: contains trashed file: "␊leading LF"
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_HT
    fstests.go:711: testing "␉leading HT"
2024/11/15 05:20:32 DEBUG : ␉leading HT/␉leading HT: calculating gcid: failed to unwrap object from src: ␉leading HT/␉leading HT
2024/11/15 05:20:32 DEBUG : ␉leading HT/␉leading HT: gcid = bac15f23a33fc97e5fb96a98bd1c536378dddb8a
2024/11/15 05:20:35 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:35 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/11/15 05:20:35 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:35 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/11/15 05:20:35 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:35 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/11/15 05:20:36 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:36 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/11/15 05:20:36 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:36 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/11/15 05:20:38 DEBUG : pacer: Reducing sleep to 1.5s
2024/11/15 05:20:40 DEBUG : pacer: Reducing sleep to 1.125s
2024/11/15 05:20:41 DEBUG : pacer: Reducing sleep to 843.75ms
2024/11/15 05:20:43 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/11/15 05:20:43 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/11/15 05:20:44 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/11/15 05:20:45 DEBUG : pacer: Reducing sleep to 266.967773ms
2024/11/15 05:20:45 DEBUG : ␉leading HT: Rmdir: contains trashed file: "␉leading HT"
2024/11/15 05:20:45 DEBUG : pacer: Reducing sleep to 200.225829ms
2024/11/15 05:20:46 DEBUG : pacer: Reducing sleep to 150.169371ms
2024/11/15 05:20:46 DEBUG : pacer: Reducing sleep to 112.627028ms
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_VT
    fstests.go:711: testing "␋leading VT"
2024/11/15 05:20:46 DEBUG : pacer: Reducing sleep to 100ms
2024/11/15 05:20:47 DEBUG : ␋leading VT/␋leading VT: calculating gcid: failed to unwrap object from src: ␋leading VT/␋leading VT
2024/11/15 05:20:47 DEBUG : ␋leading VT/␋leading VT: gcid = da3bed5861b8ceb699015d134ba8034698e2234d
2024/11/15 05:20:51 DEBUG : ␋leading VT: Rmdir: contains trashed file: "␋leading VT"
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_dot
    fstests.go:711: testing ".leading dot"
2024/11/15 05:20:54 DEBUG : .leading dot/.leading dot: calculating gcid: failed to unwrap object from src: .leading dot/.leading dot
2024/11/15 05:20:54 DEBUG : .leading dot/.leading dot: gcid = 30448ebd26366c112a39d1b76da95acf91e95e46
2024/11/15 05:20:56 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:56 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/11/15 05:20:56 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:56 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/11/15 05:20:56 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:20:56 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/11/15 05:20:57 DEBUG : pacer: Reducing sleep to 600ms
2024/11/15 05:20:58 DEBUG : pacer: Reducing sleep to 450ms
2024/11/15 05:20:58 DEBUG : pacer: Reducing sleep to 337.5ms
2024/11/15 05:20:59 DEBUG : pacer: Reducing sleep to 253.125ms
2024/11/15 05:20:59 DEBUG : pacer: Reducing sleep to 189.84375ms
2024/11/15 05:21:00 DEBUG : pacer: Reducing sleep to 142.382812ms
2024/11/15 05:21:00 DEBUG : pacer: Reducing sleep to 106.787109ms
2024/11/15 05:21:00 DEBUG : .leading dot: Rmdir: contains trashed file: ".leading dot"
2024/11/15 05:21:00 DEBUG : pacer: Reducing sleep to 100ms
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_space
    fstests.go:711: testing "trailing space "
2024/11/15 05:21:02 DEBUG : trailing space /trailing space : calculating gcid: failed to unwrap object from src: trailing space /trailing space 
2024/11/15 05:21:02 DEBUG : trailing space /trailing space : gcid = 5fe76ce3f9f6127ef8140be163bae7ee18e166ca
2024/11/15 05:21:07 DEBUG : trailing space : Rmdir: contains trashed file: "trailing space "
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_CR
    fstests.go:711: testing "trailing CR␍"
2024/11/15 05:21:09 DEBUG : trailing CR␍/trailing CR␍: calculating gcid: failed to unwrap object from src: trailing CR␍/trailing CR␍
2024/11/15 05:21:09 DEBUG : trailing CR␍/trailing CR␍: gcid = 656e58420c769915fccb9c5dc9b3cc53d38168f1
2024/11/15 05:21:13 DEBUG : trailing CR␍: Rmdir: contains trashed file: "trailing CR␍"
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_LF
    fstests.go:711: testing "trailing LF␊"
2024/11/15 05:21:15 DEBUG : trailing LF␊/trailing LF␊: calculating gcid: failed to unwrap object from src: trailing LF␊/trailing LF␊
2024/11/15 05:21:15 DEBUG : trailing LF␊/trailing LF␊: gcid = 6a27363298703256ef51610faed554193617ff6c
2024/11/15 05:21:20 DEBUG : trailing LF␊: Rmdir: contains trashed file: "trailing LF␊"
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_HT
    fstests.go:711: testing "trailing HT␉"
2024/11/15 05:21:22 DEBUG : trailing HT␉/trailing HT␉: calculating gcid: failed to unwrap object from src: trailing HT␉/trailing HT␉
2024/11/15 05:21:22 DEBUG : trailing HT␉/trailing HT␉: gcid = 8b9cfc2aa4c7fc0b02ea27ce38864584123256c5
2024/11/15 05:21:27 DEBUG : trailing HT␉: Rmdir: contains trashed file: "trailing HT␉"
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_VT
    fstests.go:711: testing "trailing VT␋"
2024/11/15 05:21:29 DEBUG : trailing VT␋/trailing VT␋: calculating gcid: failed to unwrap object from src: trailing VT␋/trailing VT␋
2024/11/15 05:21:29 DEBUG : trailing VT␋/trailing VT␋: gcid = 27828ed5f2b12f7225f29ab9a95884fa6c1fefa3
2024/11/15 05:21:31 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:21:31 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/11/15 05:21:31 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:21:31 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/11/15 05:21:32 DEBUG : pacer: Reducing sleep to 300ms
2024/11/15 05:21:32 DEBUG : pacer: Reducing sleep to 225ms
2024/11/15 05:21:32 DEBUG : pacer: Reducing sleep to 168.75ms
2024/11/15 05:21:33 DEBUG : pacer: Reducing sleep to 126.5625ms
2024/11/15 05:21:33 DEBUG : pacer: Reducing sleep to 100ms
2024/11/15 05:21:34 DEBUG : trailing VT␋: Rmdir: contains trashed file: "trailing VT␋"
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_dot
    fstests.go:711: testing "trailing dot."
2024/11/15 05:21:36 DEBUG : trailing dot./trailing dot.: calculating gcid: failed to unwrap object from src: trailing dot./trailing dot.
2024/11/15 05:21:36 DEBUG : trailing dot./trailing dot.: gcid = d53df92ce94f67ebc40ffa3f12c134813043b845
2024/11/15 05:21:38 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:21:38 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/11/15 05:21:38 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:21:38 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/11/15 05:21:39 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:21:39 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/11/15 05:21:39 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:21:39 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/11/15 05:21:40 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:21:40 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/11/15 05:21:41 DEBUG : pacer: Reducing sleep to 1.5s
2024/11/15 05:21:43 DEBUG : pacer: Reducing sleep to 1.125s
2024/11/15 05:21:45 DEBUG : pacer: Reducing sleep to 843.75ms
2024/11/15 05:21:46 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/11/15 05:21:47 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/11/15 05:21:48 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/11/15 05:21:48 DEBUG : pacer: Reducing sleep to 266.967773ms
2024/11/15 05:21:48 DEBUG : trailing dot.: Rmdir: contains trashed file: "trailing dot."
2024/11/15 05:21:48 DEBUG : pacer: Reducing sleep to 200.225829ms
2024/11/15 05:21:49 DEBUG : pacer: Reducing sleep to 150.169371ms
2024/11/15 05:21:49 DEBUG : pacer: Reducing sleep to 112.627028ms
=== RUN   TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8
    fstests.go:711: testing "invalid utf-8\xfe"
2024/11/15 05:21:50 DEBUG : pacer: Reducing sleep to 100ms
2024/11/15 05:21:50 DEBUG : invalid utf-8�/invalid utf-8�: calculating gcid: failed to unwrap object from src: invalid utf-8�/invalid utf-8�
2024/11/15 05:21:50 DEBUG : invalid utf-8�/invalid utf-8�: gcid = 03edd4d9bd410e70a16ad8de3f584578a9a79813
2024/11/15 05:21:54 DEBUG : invalid utf-8�: Rmdir: contains trashed file: "invalid utf-8\xfe"
=== RUN   TestIntegration/FsMkdir/FsEncoding/URL_encoding
    fstests.go:711: testing "test%46.txt"
2024/11/15 05:21:56 DEBUG : test%46.txt/test%46.txt: calculating gcid: failed to unwrap object from src: test%46.txt/test%46.txt
2024/11/15 05:21:56 DEBUG : test%46.txt/test%46.txt: gcid = a5f716507aa4424c18adf129ff859d78178bc8b4
2024/11/15 05:22:01 DEBUG : test%46.txt: Rmdir: contains trashed file: "test%46.txt"
=== RUN   TestIntegration/FsMkdir/FsPutFiles
2024/11/15 05:22:02 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt
2024/11/15 05:22:02 DEBUG : file name.txt: gcid = 162669660e14f443d0710b1f98d067fd33bbeb17
2024/11/15 05:22:07 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: calculating gcid: failed to unwrap object from src: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt
2024/11/15 05:22:07 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: gcid = c03a1802b94cab5062a6f658abed680e42b32754
2024/11/15 05:22:10 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt
2024/11/15 05:22:10 DEBUG : file name.txt: gcid = 3b67cf31e6884df5918536b38bbd81603856c898
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListR
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListLevel2
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPurge
2024/11/15 05:22:18 DEBUG : dirToPurge/fileToPurge.txt: calculating gcid: failed to unwrap object from src: dirToPurge/fileToPurge.txt
2024/11/15 05:22:18 DEBUG : dirToPurge/fileToPurge.txt: gcid = d2cad7a18fb904131d2773ed6e6d08ceec39ce19
2024/11/15 05:22:20 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:22:20 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/11/15 05:22:21 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:22:21 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/11/15 05:22:21 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:22:21 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/11/15 05:22:21 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:22:21 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/11/15 05:22:22 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:22:22 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/11/15 05:22:24 DEBUG : pacer: Reducing sleep to 1.5s
2024/11/15 05:22:26 DEBUG : pacer: Reducing sleep to 1.125s
2024/11/15 05:22:27 DEBUG : pacer: Reducing sleep to 843.75ms
2024/11/15 05:22:28 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/11/15 05:22:29 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/11/15 05:22:30 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/11/15 05:22:30 DEBUG : pacer: Reducing sleep to 266.967773ms
2024/11/15 05:22:31 DEBUG : pacer: Reducing sleep to 200.225829ms
2024/11/15 05:22:31 DEBUG : pacer: Reducing sleep to 150.169371ms
2024/11/15 05:22:32 DEBUG : pacer: Reducing sleep to 112.627028ms
2024/11/15 05:22:32 DEBUG : pacer: Reducing sleep to 100ms
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot
2024/11/15 05:22:34 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: calculating gcid: failed to unwrap object from src: dirToPurgeFromRoot/fileToPurgeFromRoot.txt
2024/11/15 05:22:34 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: gcid = bcf4c2b200d41e0077a8fb49bfc562dfd5c02a5a
2024/11/15 05:22:36 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:22:36 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/11/15 05:22:36 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:22:36 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/11/15 05:22:36 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:22:36 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/11/15 05:22:37 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:22:37 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/11/15 05:22:37 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:22:37 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/11/15 05:22:39 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/11/15 05:22:41 DEBUG : pacer: Reducing sleep to 1.5s
2024/11/15 05:22:43 DEBUG : pacer: Reducing sleep to 1.125s
2024/11/15 05:22:45 DEBUG : pacer: Reducing sleep to 843.75ms
2024/11/15 05:22:46 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/11/15 05:22:47 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/11/15 05:22:47 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/11/15 05:22:48 DEBUG : pacer: Reducing sleep to 266.967773ms
2024/11/15 05:22:48 DEBUG : pacer: Reducing sleep to 200.225829ms
2024/11/15 05:22:48 DEBUG : Creating backend with remote "TestPikPak:rclone-test-qodiheq8kumu/dirToPurgeFromRoot"
2024/11/15 05:22:48 DEBUG : Config file has changed externally - reloading
2024/11/15 05:22:51 DEBUG : pacer: Reducing sleep to 150.169371ms
2024/11/15 05:22:51 DEBUG : pacer: Reducing sleep to 112.627028ms
2024/11/15 05:22:51 DEBUG : pacer: Reducing sleep to 100ms
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsDirMove
2024/11/15 05:22:52 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sisatuf6yiti"
2024/11/15 05:23:01 DEBUG : PikPak root 'rclone-test-sisatuf6yiti': Purge remote
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot
    fstests.go:1915: Opening root remote "TestPikPak:" path "rclone-test-qodiheq8kumu" from "TestPikPak:rclone-test-qodiheq8kumu"
2024/11/15 05:23:02 DEBUG : Creating backend with remote "TestPikPak:"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/List
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries
2024/11/15 05:23:06 ERROR : : error listing: directory not found
--- PASS: TestIntegration (222.27s)
    --- PASS: TestIntegration/FsMkdir (220.16s)
        --- PASS: TestIntegration/FsMkdir/FsEncoding (155.20s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (14.72s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (6.56s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (14.44s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (6.49s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (14.49s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (7.83s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (14.57s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (6.54s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (8.68s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (6.55s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (6.58s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (7.12s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (6.62s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (7.02s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (14.23s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (6.22s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (6.25s)
        --- PASS: TestIntegration/FsMkdir/FsPutFiles (63.23s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (1.34s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (1.31s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.50s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (15.41s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (18.93s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (10.60s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (2.76s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (2.47s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.29s)
PASS
ok  	github.com/rclone/rclone/backend/pikpak	222.282s
"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|dot_dot|invalid_UTF-8|leading_CR|leading_HT|leading_LF|leading_VT|leading_dot|leading_space|leading_tilde|punctuation|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 3m42.956325458s (try 4/5)