"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|invalid_UTF-8|trailing_dot)$|^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/10/13 04:04:27 DEBUG : Creating backend with remote "TestPikPak:rclone-test-bebozaw2lesu"
2024/10/13 04:04:27 DEBUG : Using config file from "/home/rclone/.rclone.conf"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsEncoding
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_dot
    fstests.go:711: testing "trailing dot."
2024/10/13 04:04:30 DEBUG : trailing dot./trailing dot.: calculating gcid: failed to unwrap object from src: trailing dot./trailing dot.
2024/10/13 04:04:30 DEBUG : trailing dot./trailing dot.: gcid = 9128f9498a1246b7176e68b9331c07de35529455
2024/10/13 04:04:32 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:04:32 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/10/13 04:04:32 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:04:32 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/10/13 04:04:32 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:04:32 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/10/13 04:04:33 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:04:33 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/10/13 04:04:34 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:04:34 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/10/13 04:04:35 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:04:37 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:04:39 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:04:41 DEBUG : pacer: Reducing sleep to 1.5s
2024/10/13 04:04:43 DEBUG : pacer: Reducing sleep to 1.125s
2024/10/13 04:04:45 DEBUG : pacer: Reducing sleep to 843.75ms
2024/10/13 04:04:46 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/10/13 04:04:47 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/10/13 04:04:47 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/10/13 04:04:48 DEBUG : pacer: Reducing sleep to 266.967773ms
2024/10/13 04:04:48 DEBUG : trailing dot.: Rmdir: contains trashed file: "trailing dot."
2024/10/13 04:04:48 DEBUG : pacer: Reducing sleep to 200.225829ms
2024/10/13 04:04:49 DEBUG : pacer: Reducing sleep to 150.169371ms
2024/10/13 04:04:49 DEBUG : pacer: Reducing sleep to 112.627028ms
=== RUN   TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8
    fstests.go:711: testing "invalid utf-8\xfe"
2024/10/13 04:04:50 DEBUG : pacer: Reducing sleep to 100ms
2024/10/13 04:04:50 DEBUG : invalid utf-8þ/invalid utf-8þ: calculating gcid: failed to unwrap object from src: invalid utf-8þ/invalid utf-8þ
2024/10/13 04:04:50 DEBUG : invalid utf-8þ/invalid utf-8þ: gcid = b22ac20f6b6379e4056375eef9fde8c1cd7e5dee
2024/10/13 04:04:55 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/10/13 04:04:57 DEBUG : test%46.txt/test%46.txt: calculating gcid: failed to unwrap object from src: test%46.txt/test%46.txt
2024/10/13 04:04:57 DEBUG : test%46.txt/test%46.txt: gcid = 076b85f548716842a3f3aa88833eeb2fe8de553f
2024/10/13 04:05:00 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:00 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/10/13 04:05:01 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:01 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/10/13 04:05:01 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:01 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/10/13 04:05:01 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:01 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/10/13 04:05:02 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:02 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/10/13 04:05:04 DEBUG : pacer: Reducing sleep to 1.5s
2024/10/13 04:05:06 DEBUG : pacer: Reducing sleep to 1.125s
2024/10/13 04:05:07 DEBUG : pacer: Reducing sleep to 843.75ms
2024/10/13 04:05:08 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/10/13 04:05:09 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/10/13 04:05:10 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/10/13 04:05:10 DEBUG : pacer: Reducing sleep to 266.967773ms
2024/10/13 04:05:10 DEBUG : test%46.txt: Rmdir: contains trashed file: "test%46.txt"
2024/10/13 04:05:11 DEBUG : pacer: Reducing sleep to 200.225829ms
2024/10/13 04:05:11 DEBUG : pacer: Reducing sleep to 150.169371ms
2024/10/13 04:05:12 DEBUG : pacer: Reducing sleep to 112.627028ms
=== RUN   TestIntegration/FsMkdir/FsPutFiles
2024/10/13 04:05:12 DEBUG : pacer: Reducing sleep to 100ms
2024/10/13 04:05:12 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt
2024/10/13 04:05:12 DEBUG : file name.txt: gcid = 7ac1d0727be28d74da9928fca630ea0c60c3d8e2
2024/10/13 04:05:14 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:14 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/10/13 04:05:14 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:14 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/10/13 04:05:15 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:15 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/10/13 04:05:15 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:15 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/10/13 04:05:16 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:16 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/10/13 04:05:17 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:19 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:21 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:23 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:25 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/13 04:05:25 DEBUG : file name.txt: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VO932Igj0KeB4U5SVlUTRwvLo1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc0005a51c0), FileID:"VO932IgW0KeB4U5SVlUTRwvJo1", FileName:"file name.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x252239c0, ext:63864389112, loc:(*time.Location)(0xc000274380)}, UpdatedTime:api.Time{wall:0x2671eb40, ext:63864389112, loc:(*time.Location)(0xc000274380)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""}
2024/10/13 04:05:28 DEBUG : pacer: Reducing sleep to 1.5s
2024/10/13 04:05:29 DEBUG : pacer: Reducing sleep to 1.125s
2024/10/13 04:05:31 DEBUG : pacer: Reducing sleep to 843.75ms
2024/10/13 04:05:31 DEBUG : file name.txt: waiting 500ms for the cancellation to be effective
    fstests.go:145: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:145
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:161
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:223
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:213
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:957
        	Error:      	Received unexpected error:
        	            	can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VO932Igj0KeB4U5SVlUTRwvLo1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc0005a51c0), FileID:"VO932IgW0KeB4U5SVlUTRwvJo1", FileName:"file name.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x252239c0, ext:63864389112, loc:(*time.Location)(0xc000274380)}, UpdatedTime:api.Time{wall:0x2671eb40, ext:63864389112, loc:(*time.Location)(0xc000274380)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""}
        	Test:       	TestIntegration/FsMkdir/FsPutFiles
        	Messages:   	Put
2024/10/13 04:05:32 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/10/13 04:05:33 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/10/13 04:05:34 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/10/13 04:05:34 ERROR : : error listing: directory not found
2024/10/13 04:05:34 DEBUG : pacer: Reducing sleep to 266.967773ms
--- FAIL: TestIntegration (67.38s)
    --- FAIL: TestIntegration/FsMkdir (65.93s)
        --- PASS: TestIntegration/FsMkdir/FsEncoding (43.19s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (20.69s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (6.95s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (15.28s)
        --- FAIL: TestIntegration/FsMkdir/FsPutFiles (19.86s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/pikpak	67.397s
"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|invalid_UTF-8|trailing_dot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsDirMove|FsListDirFile2|FsListLevel2|FsListR|FsPurge|FsPurgeRoot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries)$'" - Finished ERROR in 1m8.387196804s (try 3/5): exit status 1: Failed [TestIntegration/FsMkdir/FsPutFiles]