"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$'" - Starting (try 4/5)
=== RUN   TestIntegration
    fstests.go:442: Using remote "TestPikPak:"
2025/02/11 01:38:37 DEBUG : Creating backend with remote "TestPikPak:rclone-test-wafozum0vido"
2025/02/11 01:38:37 DEBUG : Using config file from "/home/rclone/.rclone.conf"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsPutFiles
2025/02/11 01:38:40 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt
2025/02/11 01:38:40 DEBUG : file name.txt: gcid = 5e66593b8674de7c3631a54675b73ba3f6725ea8
2025/02/11 01:38:42 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:38:42 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/02/11 01:38:42 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:38:42 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/02/11 01:38:42 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:38:42 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2025/02/11 01:38:43 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:38:43 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2025/02/11 01:38:44 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:38:44 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/02/11 01:38:45 DEBUG : pacer: Reducing sleep to 1.5s
2025/02/11 01:38:47 DEBUG : pacer: Reducing sleep to 1.125s
2025/02/11 01:38:49 DEBUG : pacer: Reducing sleep to 843.75ms
2025/02/11 01:38:50 DEBUG : pacer: Reducing sleep to 632.8125ms
2025/02/11 01:38:51 DEBUG : pacer: Reducing sleep to 474.609375ms
2025/02/11 01:38:51 DEBUG : pacer: Reducing sleep to 355.957031ms
2025/02/11 01:38:52 DEBUG : pacer: Reducing sleep to 266.967773ms
2025/02/11 01:38:52 DEBUG : pacer: Reducing sleep to 200.225829ms
2025/02/11 01:38:53 DEBUG : pacer: Reducing sleep to 150.169371ms
2025/02/11 01:38:53 DEBUG : pacer: Reducing sleep to 112.627028ms
2025/02/11 01:38:53 DEBUG : pacer: Reducing sleep to 100ms
2025/02/11 01:38:53 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: calculating gcid: failed to unwrap object from src: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt
2025/02/11 01:38:53 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: gcid = adb8a1c398192848d03b1cdcfd50087ad51b2aae
2025/02/11 01:38:56 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt
2025/02/11 01:38:56 DEBUG : file name.txt: gcid = 0f7a1d17efd198e5616f0431c803b06777afc06b
2025/02/11 01:38:58 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:38:58 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/02/11 01:38:59 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:38:59 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/02/11 01:38:59 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:38:59 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2025/02/11 01:38:59 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:38:59 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2025/02/11 01:39:00 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:39:00 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/02/11 01:39:02 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:39:04 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:39:06 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:39:08 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:39:10 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/11 01:39:10 DEBUG : rcloneTemphuxaduq4: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOImeBtbPzqBXSUwey74pp86o1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc00037d580), FileID:"VOImeBtRPzqBXSUwey74pp84o1", FileName:"rcloneTemphuxaduq4", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x2c4947c0, ext:63874834736, loc:(*time.Location)(0xc000375f10)}, UpdatedTime:api.Time{wall:0x2d89b700, ext:63874834736, loc:(*time.Location)(0xc000375f10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""}
2025/02/11 01:39:12 DEBUG : pacer: Reducing sleep to 1.5s
2025/02/11 01:39:14 DEBUG : pacer: Reducing sleep to 1.125s
2025/02/11 01:39:15 DEBUG : pacer: Reducing sleep to 843.75ms
2025/02/11 01:39:15 DEBUG : rcloneTemphuxaduq4: 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:959
        	Error:      	Received unexpected error:
        	            	can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOImeBtbPzqBXSUwey74pp86o1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc00037d580), FileID:"VOImeBtRPzqBXSUwey74pp84o1", FileName:"rcloneTemphuxaduq4", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x2c4947c0, ext:63874834736, loc:(*time.Location)(0xc000375f10)}, UpdatedTime:api.Time{wall:0x2d89b700, ext:63874834736, loc:(*time.Location)(0xc000375f10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""}
        	Test:       	TestIntegration/FsMkdir/FsPutFiles
        	Messages:   	Put
2025/02/11 01:39:16 DEBUG : pacer: Reducing sleep to 632.8125ms
2025/02/11 01:39:17 DEBUG : pacer: Reducing sleep to 474.609375ms
2025/02/11 01:39:18 DEBUG : pacer: Reducing sleep to 355.957031ms
2025/02/11 01:39:18 ERROR : : error listing: directory not found
2025/02/11 01:39:18 DEBUG : pacer: Reducing sleep to 266.967773ms
--- FAIL: TestIntegration (41.60s)
    --- FAIL: TestIntegration/FsMkdir (39.20s)
        --- FAIL: TestIntegration/FsMkdir/FsPutFiles (36.33s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/pikpak	41.608s
"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$'" - Finished ERROR in 42.360038998s (try 4/5): exit status 1: Failed [TestIntegration/FsMkdir/FsPutFiles]