"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$'" - Starting (try 3/5)
=== RUN   TestIntegration
    fstests.go:442: Using remote "TestPikPak:"
2024/10/26 01:26:41 DEBUG : Creating backend with remote "TestPikPak:rclone-test-gosatok4qemo"
2024/10/26 01:26:41 DEBUG : Using config file from "/home/rclone/.rclone.conf"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsPutFiles
2024/10/26 01:26:43 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt
2024/10/26 01:26:43 DEBUG : file name.txt: gcid = d9f55a4a70fc388a5f51653c95a68fd8a22e9e90
2024/10/26 01:26:46 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:26:46 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/10/26 01:26:46 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:26:46 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/10/26 01:26:46 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:26:46 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/10/26 01:26:47 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:26:47 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/10/26 01:26:47 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:26:47 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/10/26 01:26:49 DEBUG : pacer: Reducing sleep to 1.5s
2024/10/26 01:26:51 DEBUG : pacer: Reducing sleep to 1.125s
2024/10/26 01:26:53 DEBUG : pacer: Reducing sleep to 843.75ms
2024/10/26 01:26:54 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/10/26 01:26:55 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/10/26 01:26:55 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/10/26 01:26:56 DEBUG : pacer: Reducing sleep to 266.967773ms
2024/10/26 01:26:56 DEBUG : pacer: Reducing sleep to 200.225829ms
2024/10/26 01:26:56 DEBUG : pacer: Reducing sleep to 150.169371ms
2024/10/26 01:26:57 DEBUG : pacer: Reducing sleep to 112.627028ms
2024/10/26 01:26:57 DEBUG : pacer: Reducing sleep to 100ms
2024/10/26 01:26:57 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: calculating gcid: failed to unwrap object from src: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt
2024/10/26 01:26:57 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: gcid = d731b42ff09e6b587193f68220fe25d372feb356
2024/10/26 01:27:00 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt
2024/10/26 01:27:00 DEBUG : file name.txt: gcid = 9643d8edce3d73366ae87ed7f4e3e9fd04165042
2024/10/26 01:27:02 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:27:02 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/10/26 01:27:02 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:27:02 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/10/26 01:27:02 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:27:02 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/10/26 01:27:02 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:27:02 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/10/26 01:27:03 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:27:03 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/10/26 01:27:05 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:27:07 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:27:09 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:27:11 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:27:13 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/10/26 01:27:13 DEBUG : rcloneTemplufizuc0: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOA5QkzPrz_Rz8WbzY9Ocpv9o1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc00010e300), FileID:"VOA5QkzDrz_Rz8WbzY9Ocpv7o1", FileName:"rcloneTemplufizuc0", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x12b74280, ext:63865502820, loc:(*time.Location)(0xc0001d7490)}, UpdatedTime:api.Time{wall:0x13ab6680, ext:63865502820, loc:(*time.Location)(0xc0001d7490)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""}
2024/10/26 01:27:15 DEBUG : pacer: Reducing sleep to 1.5s
2024/10/26 01:27:17 DEBUG : pacer: Reducing sleep to 1.125s
2024/10/26 01:27:18 DEBUG : pacer: Reducing sleep to 843.75ms
2024/10/26 01:27:18 DEBUG : rcloneTemplufizuc0: 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:"VOA5QkzPrz_Rz8WbzY9Ocpv9o1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc00010e300), FileID:"VOA5QkzDrz_Rz8WbzY9Ocpv7o1", FileName:"rcloneTemplufizuc0", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x12b74280, ext:63865502820, loc:(*time.Location)(0xc0001d7490)}, UpdatedTime:api.Time{wall:0x13ab6680, ext:63865502820, loc:(*time.Location)(0xc0001d7490)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""}
        	Test:       	TestIntegration/FsMkdir/FsPutFiles
        	Messages:   	Put
2024/10/26 01:27:20 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/10/26 01:27:20 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/10/26 01:27:21 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/10/26 01:27:21 ERROR : : error listing: directory not found
2024/10/26 01:27:21 DEBUG : pacer: Reducing sleep to 266.967773ms
--- FAIL: TestIntegration (40.91s)
    --- FAIL: TestIntegration/FsMkdir (38.67s)
        --- FAIL: TestIntegration/FsMkdir/FsPutFiles (35.84s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/pikpak	40.920s
"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$'" - Finished ERROR in 41.725560413s (try 3/5): exit status 1: Failed [TestIntegration/FsMkdir/FsPutFiles]