"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|invalid_UTF-8|leading_dot|trailing_CR|trailing_HT|trailing_LF|trailing_VT|trailing_dot|trailing_space)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsDirMove|FsListDirFile2|FsListLevel2|FsListR|FsListRootedSubdir|FsPurge|FsPurgeRoot|ObjectUpdate)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries|Put)$'" - Starting (try 5/5) === RUN TestIntegration fstests.go:438: Using remote "TestPikPak:" 2026/05/11 04:56:23 DEBUG : Creating backend with remote "TestPikPak:rclone-test-veyoxod2gusa" 2026/05/11 04:56:23 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:708: testing ".leading dot" 2026/05/11 04:56:27 DEBUG : .leading dot/.leading dot: calculating gcid: failed to unwrap object from src: .leading dot/.leading dot 2026/05/11 04:56:27 DEBUG : .leading dot/.leading dot: gcid = fe6630f25d423c1a6c70d4dfb7a5bdba644eb743 2026/05/11 04:56:29 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:29 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2026/05/11 04:56:29 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:29 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/05/11 04:56:30 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:30 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/05/11 04:56:30 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:30 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/05/11 04:56:31 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:31 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/11 04:56:32 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:34 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:36 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:38 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:40 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:40 DEBUG : .leading dot: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOB_hLSah_VaJ-GQfMMpSo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa02c14c0), FileID:"VOsKOB_FLSah_VaJ-GQfMMpQo2", FileName:".leading dot", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0xe9a6740, ext:63914072187, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0xfdad680, ext:63914072187, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/11 04:56:42 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/11 04:56:44 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/11 04:56:46 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/11 04:56:46 DEBUG : .leading dot: waiting 500ms for the cancellation to be effective fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:224 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:214 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:714 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOB_hLSah_VaJ-GQfMMpSo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa02c14c0), FileID:"VOsKOB_FLSah_VaJ-GQfMMpQo2", FileName:".leading dot", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0xe9a6740, ext:63914072187, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0xfdad680, ext:63914072187, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/leading_dot Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:708: testing "trailing space " 2026/05/11 04:56:47 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/11 04:56:48 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/11 04:56:49 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/11 04:56:49 DEBUG : trailing space /trailing space : calculating gcid: failed to unwrap object from src: trailing space /trailing space 2026/05/11 04:56:49 DEBUG : trailing space /trailing space : gcid = 1e69928b28e47b3ab101dba8e05b65f5cd8e9246 2026/05/11 04:56:49 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/11 04:56:50 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/11 04:56:51 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:51 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/11 04:56:51 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:51 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/11 04:56:52 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:52 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/11 04:56:52 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:52 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/11 04:56:54 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:56 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:56:58 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:00 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:02 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:04 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:04 DEBUG : trailing space : canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOGyzySgp_jifiaHJHJjQo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa054e200), FileID:"VOsKOGyiySgp_jifiaHJHJjNo2", FileName:"trailing space␠", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1471c3c0, ext:63914072209, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x16b59940, ext:63914072209, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/11 04:57:06 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/11 04:57:08 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/11 04:57:10 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/11 04:57:10 DEBUG : trailing space : waiting 500ms for the cancellation to be effective fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:224 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:214 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:714 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOGyzySgp_jifiaHJHJjQo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa054e200), FileID:"VOsKOGyiySgp_jifiaHJHJjNo2", FileName:"trailing space␠", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1471c3c0, ext:63914072209, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x16b59940, ext:63914072209, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/trailing_space Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:708: testing "trailing CR␍" 2026/05/11 04:57:11 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/11 04:57:12 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/11 04:57:12 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/11 04:57:12 DEBUG : trailing CR␍/trailing CR␍: calculating gcid: failed to unwrap object from src: trailing CR␍/trailing CR␍ 2026/05/11 04:57:12 DEBUG : trailing CR␍/trailing CR␍: gcid = 3e7ef63c773415b4731e5e130bec7ec31643f62c 2026/05/11 04:57:13 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/11 04:57:14 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/11 04:57:15 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:15 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/11 04:57:15 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:15 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/11 04:57:15 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:15 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/11 04:57:16 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:16 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/11 04:57:18 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:20 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:22 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:24 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:26 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:28 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:28 DEBUG : trailing CR␍: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOMkZcn7Ku0VP0fje10M_o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa037bb40), FileID:"VOsKOMkLcn7Ku0VP0fje10MXo2", FileName:"trailing CR␍", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x3b5dc100, ext:63914072232, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x112a880, ext:63914072233, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/11 04:57:30 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/11 04:57:32 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/11 04:57:33 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/11 04:57:33 DEBUG : trailing CR␍: waiting 500ms for the cancellation to be effective fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:224 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:214 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:714 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOMkZcn7Ku0VP0fje10M_o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa037bb40), FileID:"VOsKOMkLcn7Ku0VP0fje10MXo2", FileName:"trailing CR␍", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x3b5dc100, ext:63914072232, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x112a880, ext:63914072233, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/trailing_CR Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:708: testing "trailing LF␊" 2026/05/11 04:57:34 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/11 04:57:35 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/11 04:57:36 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/11 04:57:36 DEBUG : trailing LF␊/trailing LF␊: calculating gcid: failed to unwrap object from src: trailing LF␊/trailing LF␊ 2026/05/11 04:57:36 DEBUG : trailing LF␊/trailing LF␊: gcid = beb1c1e28dc1cfd26c771b8d17907d78562a8163 2026/05/11 04:57:36 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/11 04:57:38 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/11 04:57:38 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:38 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/11 04:57:39 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:39 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/11 04:57:39 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:39 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/11 04:57:40 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:40 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/11 04:57:41 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:43 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:45 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:47 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:49 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:51 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:57:51 DEBUG : trailing LF␊: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOSX8TXN5L5GxxIb4pUBgo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa0237400), FileID:"VOsKOSWmTXN5L5GxxIb4pUBco2", FileName:"trailing LF␊", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x26aef440, ext:63914072256, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x2793d600, ext:63914072256, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/11 04:57:53 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/11 04:57:55 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/11 04:57:57 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/11 04:57:57 DEBUG : trailing LF␊: waiting 500ms for the cancellation to be effective fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:224 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:214 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:714 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOSX8TXN5L5GxxIb4pUBgo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa0237400), FileID:"VOsKOSWmTXN5L5GxxIb4pUBco2", FileName:"trailing LF␊", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x26aef440, ext:63914072256, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x2793d600, ext:63914072256, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/trailing_LF Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:708: testing "trailing HT␉" 2026/05/11 04:57:58 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/11 04:57:59 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/11 04:58:00 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/11 04:58:00 DEBUG : trailing HT␉/trailing HT␉: calculating gcid: failed to unwrap object from src: trailing HT␉/trailing HT␉ 2026/05/11 04:58:00 DEBUG : trailing HT␉/trailing HT␉: gcid = b6bf0041eeaaf1d28f7c5f29b554a984ea742a56 2026/05/11 04:58:00 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/11 04:58:01 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/11 04:58:02 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:02 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/11 04:58:02 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:02 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/11 04:58:03 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:03 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/11 04:58:03 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:03 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/11 04:58:05 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:07 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:09 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:11 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:13 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:15 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:15 DEBUG : trailing HT␉: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOYJt3lbORQYZSBxSb-mgo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa02c1ec0), FileID:"VOsKOYJU3lbORQYZSBxSb-mHo2", FileName:"trailing HT␉", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x16789040, ext:63914072280, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x177bf680, ext:63914072280, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/11 04:58:17 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/11 04:58:19 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/11 04:58:21 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/11 04:58:21 DEBUG : trailing HT␉: waiting 500ms for the cancellation to be effective fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:224 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:214 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:714 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOYJt3lbORQYZSBxSb-mgo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa02c1ec0), FileID:"VOsKOYJU3lbORQYZSBxSb-mHo2", FileName:"trailing HT␉", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x16789040, ext:63914072280, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x177bf680, ext:63914072280, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/trailing_HT Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:708: testing "trailing VT␋" 2026/05/11 04:58:22 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/11 04:58:23 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/11 04:58:23 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/11 04:58:23 DEBUG : trailing VT␋/trailing VT␋: calculating gcid: failed to unwrap object from src: trailing VT␋/trailing VT␋ 2026/05/11 04:58:23 DEBUG : trailing VT␋/trailing VT␋: gcid = 2551a851747f2443ef566d18baf89044f9d8d7e6 2026/05/11 04:58:24 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/11 04:58:25 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/11 04:58:26 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:26 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/11 04:58:26 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:26 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/11 04:58:26 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:26 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/11 04:58:27 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:27 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/11 04:58:29 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:31 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:33 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:35 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:37 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:39 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:39 DEBUG : trailing VT␋: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOd6c0L_iSjvZ6dR7aYm_o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa037a140), FileID:"VOsKOd5Z0L_iSjvZ6dR7aYmDo2", FileName:"trailing VT␋", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x632ea00, ext:63914072304, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x7de2900, ext:63914072304, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/11 04:58:41 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/11 04:58:43 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/11 04:58:44 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/11 04:58:44 DEBUG : trailing VT␋: waiting 500ms for the cancellation to be effective fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:224 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:214 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:714 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOd6c0L_iSjvZ6dR7aYm_o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa037a140), FileID:"VOsKOd5Z0L_iSjvZ6dR7aYmDo2", FileName:"trailing VT␋", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x632ea00, ext:63914072304, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x7de2900, ext:63914072304, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/trailing_VT Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:708: testing "trailing dot." 2026/05/11 04:58:46 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/11 04:58:46 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/11 04:58:47 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/11 04:58:47 DEBUG : trailing dot./trailing dot.: calculating gcid: failed to unwrap object from src: trailing dot./trailing dot. 2026/05/11 04:58:47 DEBUG : trailing dot./trailing dot.: gcid = 8eabb0c794460eb564b755e663446b9cb1384a64 2026/05/11 04:58:48 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/11 04:58:49 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/11 04:58:50 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:50 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/11 04:58:50 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:50 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/11 04:58:50 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:50 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/11 04:58:51 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:51 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/11 04:58:53 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:55 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:57 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:58:59 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:01 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:03 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:03 DEBUG : trailing dot.: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOiuCV4Vpvvc4MrDAdOozo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa037a540), FileID:"VOsKOituV4Vpvvc4MrDAdOouo2", FileName:"trailing dot.", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x30ef7740, ext:63914072327, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x329ab640, ext:63914072327, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/11 04:59:05 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/11 04:59:07 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/11 04:59:08 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/11 04:59:08 DEBUG : trailing dot.: waiting 500ms for the cancellation to be effective fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:224 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:214 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:714 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOiuCV4Vpvvc4MrDAdOozo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa037a540), FileID:"VOsKOituV4Vpvvc4MrDAdOouo2", FileName:"trailing dot.", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x30ef7740, ext:63914072327, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x329ab640, ext:63914072327, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/trailing_dot Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:708: testing "invalid utf-8\xfe" 2026/05/11 04:59:09 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/11 04:59:10 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/11 04:59:11 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/11 04:59:11 DEBUG : invalid utf-8/invalid utf-8: calculating gcid: failed to unwrap object from src: invalid utf-8/invalid utf-8 2026/05/11 04:59:11 DEBUG : invalid utf-8/invalid utf-8: gcid = 5fee375565b5ee6d4e8cf35dec7b4d863ef1d605 2026/05/11 04:59:11 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/11 04:59:13 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/11 04:59:13 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:13 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/11 04:59:14 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:14 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/11 04:59:14 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:14 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/11 04:59:15 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:15 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/11 04:59:16 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:18 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:20 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:22 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:24 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:26 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:26 DEBUG : invalid utf-8: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOoiL3lbORQYZSBxSb3Cgo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa03d3100), FileID:"VOsKOoi33lbORQYZSBxSb3Cao2", FileName:"invalid utf-8‛FE", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x26071b80, ext:63914072351, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x26ebfd40, ext:63914072351, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/11 04:59:28 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/11 04:59:30 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/11 04:59:32 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/11 04:59:32 DEBUG : invalid utf-8: waiting 500ms for the cancellation to be effective fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:224 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:214 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:714 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOoiL3lbORQYZSBxSb3Cgo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa03d3100), FileID:"VOsKOoi33lbORQYZSBxSb3Cao2", FileName:"invalid utf-8‛FE", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x26071b80, ext:63914072351, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x26ebfd40, ext:63914072351, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:708: testing "test%46.txt" 2026/05/11 04:59:33 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/11 04:59:34 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/11 04:59:35 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/11 04:59:35 DEBUG : test%46.txt/test%46.txt: calculating gcid: failed to unwrap object from src: test%46.txt/test%46.txt 2026/05/11 04:59:35 DEBUG : test%46.txt/test%46.txt: gcid = 7826ed3b58cfd21a5c0d2e2d9a6f56215118a6ea 2026/05/11 04:59:35 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/11 04:59:36 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/11 04:59:37 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:37 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/11 04:59:37 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:37 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/11 04:59:38 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:38 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/11 04:59:39 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:39 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/11 04:59:40 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:42 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:44 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:46 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:48 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:50 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 04:59:50 DEBUG : test%46.txt: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOuW-8nT_95fVzZ4b3t3Mo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa05de8c0), FileID:"VOsKOuVe8nT_95fVzZ4b3t3Io2", FileName:"test%46.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x15752a00, ext:63914072375, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x1687d280, ext:63914072375, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/11 04:59:52 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/11 04:59:54 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/11 04:59:56 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/11 04:59:56 DEBUG : test%46.txt: waiting 500ms for the cancellation to be effective fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:224 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:214 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:714 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKOuW-8nT_95fVzZ4b3t3Mo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa05de8c0), FileID:"VOsKOuVe8nT_95fVzZ4b3t3Io2", FileName:"test%46.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x15752a00, ext:63914072375, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x1687d280, ext:63914072375, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/URL_encoding Messages: Put === RUN TestIntegration/FsMkdir/FsPutFiles 2026/05/11 04:59:57 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/11 04:59:57 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2026/05/11 04:59:57 DEBUG : file name.txt: gcid = 7ff98118791d553d6f86dd8d8ba9a7dd6143ce20 2026/05/11 04:59:58 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/11 04:59:59 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/11 05:00:00 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 05:00:00 DEBUG : pacer: Rate limited, increasing sleep to 711.914062ms 2026/05/11 05:00:00 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 05:00:00 DEBUG : pacer: Rate limited, increasing sleep to 1.423828124s 2026/05/11 05:00:01 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 05:00:01 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/11 05:00:02 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 05:00:04 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 05:00:06 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 05:00:08 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 05:00:10 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 05:00:12 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 05:00:14 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/11 05:00:14 DEBUG : file name.txt: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKP-1ZGsfAwogjE8Jzp8aCo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa03d3c40), FileID:"VOsKP-1GGsfAwogjE8Jzp8a7o2", FileName:"file name.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x3ae3af00, ext:63914072397, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x1f78a40, ext:63914072398, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/11 05:00:16 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/11 05:00:18 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/11 05:00:20 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/11 05:00:20 DEBUG : file name.txt: waiting 500ms for the cancellation to be effective fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:224 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:214 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:958 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsKP-1ZGsfAwogjE8Jzp8aCo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x2e4fa03d3c40), FileID:"VOsKP-1GGsfAwogjE8Jzp8a7o2", FileName:"file name.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x3ae3af00, ext:63914072397, loc:(*time.Location)(0x2e4fa02cca10)}, UpdatedTime:api.Time{wall:0x1f78a40, ext:63914072398, loc:(*time.Location)(0x2e4fa02cca10)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsPutFiles Messages: Put 2026/05/11 05:00:21 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/11 05:00:22 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/11 05:00:23 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/11 05:00:23 ERROR : error listing: directory not found 2026/05/11 05:00:23 DEBUG : pacer: Reducing sleep to 266.967773ms --- FAIL: TestIntegration (240.23s) --- FAIL: TestIntegration/FsMkdir (237.93s) --- FAIL: TestIntegration/FsMkdir/FsEncoding (210.80s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_dot (20.77s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_space (23.66s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_CR (23.67s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_LF (23.69s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_HT (23.64s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_VT (23.81s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_dot (23.82s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (23.71s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/URL_encoding (23.75s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles (24.28s) FAIL exit status 1 FAIL github.com/rclone/rclone/backend/pikpak 240.248s "go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|invalid_UTF-8|leading_dot|trailing_CR|trailing_HT|trailing_LF|trailing_VT|trailing_dot|trailing_space)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^(FsDirMove|FsListDirFile2|FsListLevel2|FsListR|FsListRootedSubdir|FsPurge|FsPurgeRoot|ObjectUpdate)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries|Put)$'" - Finished ERROR in 4m1.316505974s (try 5/5): exit status 1: Failed [TestIntegration/FsMkdir/FsEncoding/leading_dot TestIntegration/FsMkdir/FsEncoding/trailing_space TestIntegration/FsMkdir/FsEncoding/trailing_CR TestIntegration/FsMkdir/FsEncoding/trailing_LF TestIntegration/FsMkdir/FsEncoding/trailing_HT TestIntegration/FsMkdir/FsEncoding/trailing_VT TestIntegration/FsMkdir/FsEncoding/trailing_dot TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 TestIntegration/FsMkdir/FsEncoding/URL_encoding TestIntegration/FsMkdir/FsPutFiles]