"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|invalid_UTF-8|trailing_CR|trailing_HT|trailing_LF|trailing_VT|trailing_dot|trailing_space)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$'" - Starting (try 2/5) === RUN TestIntegration fstests.go:442: Using remote "TestPikPak:" 2024/12/22 02:35:58 DEBUG : Creating backend with remote "TestPikPak:rclone-test-pemifoh3yoya" 2024/12/22 02:35:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:711: testing "trailing space " 2024/12/22 02:36:01 DEBUG : trailing space /trailing space : calculating gcid: failed to unwrap object from src: trailing space /trailing space 2024/12/22 02:36:01 DEBUG : trailing space /trailing space : gcid = efbffee74758560abdb8096ab35834004afbca0f 2024/12/22 02:36:05 DEBUG : trailing space : Rmdir: contains trashed file: "trailing space " === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:711: testing "trailing CR␍" 2024/12/22 02:36:07 DEBUG : trailing CR␍/trailing CR␍: calculating gcid: failed to unwrap object from src: trailing CR␍/trailing CR␍ 2024/12/22 02:36:07 DEBUG : trailing CR␍/trailing CR␍: gcid = f78d4174ce2d47ae66224ae4441dc83dfbbaf3b6 2024/12/22 02:36:09 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:09 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/12/22 02:36:10 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:10 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/12/22 02:36:10 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:10 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/12/22 02:36:10 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:10 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/12/22 02:36:11 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:11 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/12/22 02:36:13 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:15 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:17 DEBUG : pacer: Reducing sleep to 1.5s 2024/12/22 02:36:19 DEBUG : pacer: Reducing sleep to 1.125s 2024/12/22 02:36:20 DEBUG : pacer: Reducing sleep to 843.75ms 2024/12/22 02:36:21 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/12/22 02:36:22 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/12/22 02:36:23 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/12/22 02:36:23 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/12/22 02:36:23 DEBUG : trailing CR␍: Rmdir: contains trashed file: "trailing CR␍" 2024/12/22 02:36:24 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/12/22 02:36:24 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/12/22 02:36:25 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:711: testing "trailing LF␊" 2024/12/22 02:36:25 DEBUG : pacer: Reducing sleep to 100ms 2024/12/22 02:36:25 DEBUG : trailing LF␊/trailing LF␊: calculating gcid: failed to unwrap object from src: trailing LF␊/trailing LF␊ 2024/12/22 02:36:25 DEBUG : trailing LF␊/trailing LF␊: gcid = 27bcfec96400558114def193c8835d265d27b8b4 2024/12/22 02:36:29 DEBUG : trailing LF␊: Rmdir: contains trashed file: "trailing LF␊" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:711: testing "trailing HT␉" 2024/12/22 02:36:31 DEBUG : trailing HT␉/trailing HT␉: calculating gcid: failed to unwrap object from src: trailing HT␉/trailing HT␉ 2024/12/22 02:36:31 DEBUG : trailing HT␉/trailing HT␉: gcid = 83b1950d9cb023d9f57ee764830f8841e50dd085 2024/12/22 02:36:33 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:33 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/12/22 02:36:34 DEBUG : pacer: Reducing sleep to 150ms 2024/12/22 02:36:34 DEBUG : pacer: Reducing sleep to 112.5ms 2024/12/22 02:36:34 DEBUG : pacer: Reducing sleep to 100ms 2024/12/22 02:36:36 DEBUG : trailing HT␉: Rmdir: contains trashed file: "trailing HT␉" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:711: testing "trailing VT␋" 2024/12/22 02:36:38 DEBUG : trailing VT␋/trailing VT␋: calculating gcid: failed to unwrap object from src: trailing VT␋/trailing VT␋ 2024/12/22 02:36:38 DEBUG : trailing VT␋/trailing VT␋: gcid = ea3201f8c5a20459dc517d4e7e7ea313c1cf1054 2024/12/22 02:36:42 DEBUG : trailing VT␋: Rmdir: contains trashed file: "trailing VT␋" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:711: testing "trailing dot." 2024/12/22 02:36:43 DEBUG : trailing dot./trailing dot.: calculating gcid: failed to unwrap object from src: trailing dot./trailing dot. 2024/12/22 02:36:43 DEBUG : trailing dot./trailing dot.: gcid = 8c4d7e544e098459c19cce2c013e89568bcae72d 2024/12/22 02:36:48 DEBUG : trailing dot.: Rmdir: contains trashed file: "trailing dot." === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:711: testing "invalid utf-8\xfe" 2024/12/22 02:36:50 DEBUG : invalid utf-8/invalid utf-8: calculating gcid: failed to unwrap object from src: invalid utf-8/invalid utf-8 2024/12/22 02:36:50 DEBUG : invalid utf-8/invalid utf-8: gcid = b3383e33ee84a570223eea3d7b3353d6dc9b1191 2024/12/22 02:36:52 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:52 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/12/22 02:36:53 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:53 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/12/22 02:36:53 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:53 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/12/22 02:36:53 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:53 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/12/22 02:36:54 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:36:54 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/12/22 02:36:56 DEBUG : pacer: Reducing sleep to 1.5s 2024/12/22 02:36:58 DEBUG : pacer: Reducing sleep to 1.125s 2024/12/22 02:36:59 DEBUG : pacer: Reducing sleep to 843.75ms 2024/12/22 02:37:00 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/12/22 02:37:01 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/12/22 02:37:02 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/12/22 02:37:02 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/12/22 02:37:02 DEBUG : invalid utf-8: Rmdir: contains trashed file: "invalid utf-8\xfe" 2024/12/22 02:37:03 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/12/22 02:37:03 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/12/22 02:37:04 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:711: testing "test%46.txt" 2024/12/22 02:37:04 DEBUG : pacer: Reducing sleep to 100ms 2024/12/22 02:37:04 DEBUG : test%46.txt/test%46.txt: calculating gcid: failed to unwrap object from src: test%46.txt/test%46.txt 2024/12/22 02:37:04 DEBUG : test%46.txt/test%46.txt: gcid = dd5f678905214d634951190e29c5c278c4752e3b 2024/12/22 02:37:08 DEBUG : test%46.txt: Rmdir: contains trashed file: "test%46.txt" === RUN TestIntegration/FsMkdir/FsPutFiles 2024/12/22 02:37:09 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2024/12/22 02:37:09 DEBUG : file name.txt: gcid = 682f235b44c429fe1401ac040f0fd0ff51f72236 2024/12/22 02:37:12 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:37:12 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/12/22 02:37:12 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:37:12 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/12/22 02:37:12 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:37:12 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/12/22 02:37:12 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:37:12 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/12/22 02:37:13 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:37:13 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/12/22 02:37:15 DEBUG : pacer: Reducing sleep to 1.5s 2024/12/22 02:37:17 DEBUG : pacer: Reducing sleep to 1.125s 2024/12/22 02:37:18 DEBUG : pacer: Reducing sleep to 843.75ms 2024/12/22 02:37:21 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/12/22 02:37:21 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/12/22 02:37:21 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/12/22 02:37:22 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/12/22 02:37:22 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/12/22 02:37:23 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/12/22 02:37:23 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/12/22 02:37:23 DEBUG : pacer: Reducing sleep to 100ms 2024/12/22 02:37:23 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: calculating gcid: failed to unwrap object from src: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt 2024/12/22 02:37:23 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: gcid = 0b16a2c9a6298500ea357ce6eb9b8653bf9ab694 2024/12/22 02:37:26 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2024/12/22 02:37:26 DEBUG : file name.txt: gcid = c3702c352806da187c9d690ccda413b467c48287 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:403: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2024/12/22 02:37:32 DEBUG : Creating backend with remote "TestPikPak:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:403: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:403: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:403: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1125: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2024/12/22 02:37:38 DEBUG : dirToPurge/fileToPurge.txt: calculating gcid: failed to unwrap object from src: dirToPurge/fileToPurge.txt 2024/12/22 02:37:38 DEBUG : dirToPurge/fileToPurge.txt: gcid = c6f12c64ae21c2f2dcdaf59d0d1a18679cdf8eef === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2024/12/22 02:37:45 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: calculating gcid: failed to unwrap object from src: dirToPurgeFromRoot/fileToPurgeFromRoot.txt 2024/12/22 02:37:45 DEBUG : dirToPurgeFromRoot/fileToPurgeFromRoot.txt: gcid = bc4eae60956b477e909d69d2a7fe8f6bd80ada32 2024/12/22 02:37:48 DEBUG : Creating backend with remote "TestPikPak:rclone-test-pemifoh3yoya/dirToPurgeFromRoot" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2024/12/22 02:37:52 DEBUG : Creating backend with remote "TestPikPak:rclone-test-pemifoh3yoya/hello? sausage/êé" 2024/12/22 02:37:55 DEBUG : a/b/c/d/e.txt: calculating gcid: failed to unwrap object from src: a/b/c/d/e.txt 2024/12/22 02:37:55 DEBUG : a/b/c/d/e.txt: gcid = 957e8c95f08618c62c4a6ab8199aaf388f5e77b3 2024/12/22 02:37:57 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:37:57 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/12/22 02:37:57 DEBUG : pacer: Reducing sleep to 150ms 2024/12/22 02:37:57 DEBUG : pacer: Reducing sleep to 112.5ms 2024/12/22 02:37:57 DEBUG : pacer: Reducing sleep to 100ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2024/12/22 02:38:00 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Can't copy - same parent fstests.go:1269: FS can't copy === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1402: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2024/12/22 02:38:13 DEBUG : Creating backend with remote "TestPikPak:rclone-test-kexanoc9mume" 2024/12/22 02:38:23 DEBUG : PikPak root 'rclone-test-kexanoc9mume': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2024/12/22 02:38:24 DEBUG : : Rmdir: contains trashed file: "moveTest" 2024/12/22 02:38:24 DEBUG : : Rmdir: contains trashed file: "dirToPurgeFromRoot" 2024/12/22 02:38:24 DEBUG : : Rmdir: contains trashed file: "dirToPurge" 2024/12/22 02:38:24 DEBUG : : Rmdir: contains file: "hello? sausage" === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata fstests.go:1627: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1698: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime fstests.go:1755: can't set modified time === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2024/12/22 02:38:36 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt-should-be-ignored.bin 2024/12/22 02:38:36 DEBUG : file name.txt: gcid = 7f3d02626d224df708432dae48972ac861e4b580 2024/12/22 02:38:38 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:38:38 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/12/22 02:38:38 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:38:38 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/12/22 02:38:38 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:38:38 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/12/22 02:38:39 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:38:39 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/12/22 02:38:39 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:38:39 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/12/22 02:38:41 DEBUG : pacer: Reducing sleep to 1.5s 2024/12/22 02:38:43 DEBUG : pacer: Reducing sleep to 1.125s 2024/12/22 02:38:45 DEBUG : pacer: Reducing sleep to 843.75ms 2024/12/22 02:38:46 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/12/22 02:38:47 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/12/22 02:38:47 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/12/22 02:38:48 DEBUG : pacer: Reducing sleep to 266.967773ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2024/12/22 02:38:48 DEBUG : pacer: Reducing sleep to 200.225829ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2024/12/22 02:38:48 DEBUG : Creating backend with remote "TestPikPak:rclone-test-pemifoh3yoya/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2024/12/22 02:38:52 DEBUG : Creating backend with remote "TestPikPak:rclone-test-pemifoh3yoya/not found.txt" 2024/12/22 02:38:53 ERROR : : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1915: Opening root remote "TestPikPak:" path "rclone-test-pemifoh3yoya" from "TestPikPak:rclone-test-pemifoh3yoya" 2024/12/22 02:38:53 DEBUG : Creating backend with remote "TestPikPak:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1949: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2024/12/22 02:38:56 DEBUG : rclone-test-pemifoh3yoya/created from root.txt: calculating gcid: failed to unwrap object from src: rclone-test-pemifoh3yoya/created from root.txt 2024/12/22 02:38:56 DEBUG : rclone-test-pemifoh3yoya/created from root.txt: gcid = 957022636b65f647e7e2a30ec8ad4892b4d46fa6 2024/12/22 02:38:58 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:38:58 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/12/22 02:38:58 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:38:58 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/12/22 02:38:59 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:38:59 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/12/22 02:38:59 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:38:59 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/12/22 02:39:00 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/12/22 02:39:00 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/12/22 02:39:02 DEBUG : pacer: Reducing sleep to 1.5s 2024/12/22 02:39:04 DEBUG : pacer: Reducing sleep to 1.125s 2024/12/22 02:39:05 DEBUG : pacer: Reducing sleep to 843.75ms 2024/12/22 02:39:06 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/12/22 02:39:07 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/12/22 02:39:08 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/12/22 02:39:08 DEBUG : pacer: Reducing sleep to 266.967773ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2024/12/22 02:39:08 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/12/22 02:39:09 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/12/22 02:39:09 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/12/22 02:39:10 DEBUG : pacer: Reducing sleep to 100ms === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink 2024/12/22 02:39:11 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/12/22 02:39:11 DEBUG : PikPak root 'rclone-test-pemifoh3yoya': attempting to share single file 'file name.txt_does_not_exist' 2024/12/22 02:39:11 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/12/22 02:39:11 DEBUG : pacer: Reducing sleep to 100ms 2024/12/22 02:39:11 DEBUG : PikPak root 'rclone-test-pemifoh3yoya': attempting to share single file 'file name.txt' 2024/12/22 02:39:12 DEBUG : PikPak root 'rclone-test-pemifoh3yoya': attempting to share single file 'hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt' 2024/12/22 02:39:13 DEBUG : PikPak root 'rclone-test-pemifoh3yoya': attempting to share single file 'file name.txt' 2024/12/22 02:39:13 DEBUG : PikPak root 'rclone-test-pemifoh3yoya': attempting to share directory 'hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠' 2024/12/22 02:39:14 DEBUG : PikPak root 'rclone-test-pemifoh3yoya': attempting to share directory 'hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠' 2024/12/22 02:39:14 DEBUG : Creating backend with remote "TestPikPak:rclone-test-lomehaz7zoye" 2024/12/22 02:39:14 DEBUG : Config file has changed externally - reloading 2024/12/22 02:39:15 DEBUG : somefile: calculating gcid: failed to unwrap object from src: somefile 2024/12/22 02:39:15 DEBUG : somefile: gcid = d456106d13ee99ab37cf25b9eeb2c5e7d242ed3c 2024/12/22 02:39:16 DEBUG : PikPak root 'rclone-test-pemifoh3yoya': attempting to share directory '' 2024/12/22 02:39:16 DEBUG : PikPak root 'rclone-test-lomehaz7zoye': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:416: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2113: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream fstests.go:2165: FS has no PutStream interface === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:2209: *pikpak.Fs does not implement InternalTester 2024/12/22 02:39:21 ERROR : : error listing: directory not found --- PASS: TestIntegration (203.22s) --- PASS: TestIntegration/FsMkdir (201.08s) --- PASS: TestIntegration/FsMkdir/FsEncoding (69.06s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (6.19s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (17.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (6.16s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (6.10s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (5.93s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (6.25s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (14.59s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (5.68s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (130.32s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (1.22s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (1.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.91s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (1.03s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.45s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.53s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (1.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.23s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (1.14s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.22s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (7.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (7.53s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (8.05s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy (0.23s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (13.47s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (10.69s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.31s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.24s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.24s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.24s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.25s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.24s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.24s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (0.24s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (0.25s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (0.24s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.24s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (1.78s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (2.10s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (2.90s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (1.81s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (12.31s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.44s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (3.64s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (1.73s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (16.99s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (2.25s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.38s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (14.36s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (2.34s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (6.32s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (2.53s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.27s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal (0.00s) PASS ok github.com/rclone/rclone/backend/pikpak 203.234s "go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|invalid_UTF-8|trailing_CR|trailing_HT|trailing_LF|trailing_VT|trailing_dot|trailing_space)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$'" - Finished OK in 3m24.202676587s (try 2/5)