"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|dot_dot|invalid_UTF-8|leading_CR|leading_HT|leading_LF|leading_VT|leading_dot|leading_space|leading_tilde|punctuation|trailing_CR|trailing_HT|trailing_LF|trailing_VT|trailing_dot|trailing_space)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$'" - Starting (try 5/5) === RUN TestIntegration fstests.go:438: Using remote "TestPikPak:" 2026/05/10 03:16:07 DEBUG : Creating backend with remote "TestPikPak:rclone-test-qoqedif9voxa" 2026/05/10 03:16:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:708: testing ".." 2026/05/10 03:16:10 DEBUG : ../..: calculating gcid: failed to unwrap object from src: ../.. 2026/05/10 03:16:10 DEBUG : ../..: gcid = 9cb831eaf34f9ebaaa322e674631f4a0607ff033 2026/05/10 03:16:12 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:12 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2026/05/10 03:16:12 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:12 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/05/10 03:16:12 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:12 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/05/10 03:16:13 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:13 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/05/10 03:16:14 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2026/05/10 03:16:14 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:14 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:16:15 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:17 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:19 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:21 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:23 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:23 DEBUG : ..: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEsdsdLSahb5zuLhSgFGH-o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3946200), FileID:"VOsEsdsNLSahb5zuLhSgFGGwo2", FileName:"..", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1860d840, ext:63913979770, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x19920540, ext:63913979770, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:16:25 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:16:27 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:16:29 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:16:29 DEBUG : ..: 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:"VOsEsdsdLSahb5zuLhSgFGH-o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3946200), FileID:"VOsEsdsNLSahb5zuLhSgFGGwo2", FileName:"..", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1860d840, ext:63913979770, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x19920540, ext:63913979770, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/dot_dot Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:708: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/05/10 03:16:30 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:16:31 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:16:31 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:16:31 DEBUG : !"#$%&'()*+,-./:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~: calculating gcid: failed to unwrap object from src: !"#$%&'()*+,-./:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~ 2026/05/10 03:16:31 DEBUG : !"#$%&'()*+,-./:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~: gcid = 9bff0c965b790b28bd5ffcef46311a2f41da2d49 2026/05/10 03:16:32 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:16:33 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:16:34 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:34 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:16:34 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:34 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:16:35 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:35 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:16:35 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:35 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:16:37 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:39 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:41 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:43 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:45 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:47 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:47 DEBUG : !"#$%&'()*+,-./:;<=>?@[\]^_`{|}~: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEsjDalYLADoMYgilGINSeo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3696940), FileID:"VOsEsjDDlYLADoMYgilGINSYo2", FileName:"!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x11861580, ext:63913979792, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x17e6c640, ext:63913979792, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:16:49 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:16:51 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:16:52 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:16:52 DEBUG : !"#$%&'()*+,-./:;<=>?@[\]^_`{|}~: 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:"VOsEsjDalYLADoMYgilGINSeo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3696940), FileID:"VOsEsjDDlYLADoMYgilGINSYo2", FileName:"!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x11861580, ext:63913979792, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x17e6c640, ext:63913979792, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/punctuation Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:708: testing " leading space" 2026/05/10 03:16:54 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:16:54 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:16:55 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:16:55 DEBUG : leading space/ leading space: calculating gcid: failed to unwrap object from src: leading space/ leading space 2026/05/10 03:16:55 DEBUG : leading space/ leading space: gcid = 20ccf8d58d44f7976dfa90c29020a7241576a638 2026/05/10 03:16:56 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:16:57 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:16:58 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:58 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:16:58 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:58 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:16:58 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:58 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:16:59 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:16:59 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:17:01 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:03 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:05 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:07 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:09 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:11 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:11 DEBUG : leading space: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEsp-rySgp_jifiaHHN2b-o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3697ac0), FileID:"VOsEsp-OySgp_jifiaHHN2avo2", FileName:"␠leading space", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x3b1175c0, ext:63913979815, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x989680, ext:63913979816, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:17:13 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:17:15 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:17:16 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:17:16 DEBUG : leading 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:"VOsEsp-rySgp_jifiaHHN2b-o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3697ac0), FileID:"VOsEsp-OySgp_jifiaHHN2avo2", FileName:"␠leading space", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x3b1175c0, ext:63913979815, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x989680, ext:63913979816, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/leading_space Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:708: testing "~leading tilde" 2026/05/10 03:17:17 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:17:18 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:17:19 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:17:19 DEBUG : ~leading tilde/~leading tilde: calculating gcid: failed to unwrap object from src: ~leading tilde/~leading tilde 2026/05/10 03:17:19 DEBUG : ~leading tilde/~leading tilde: gcid = 2d2b17d6f7951fc6568bcea6b631b7c4c42ee7cf 2026/05/10 03:17:19 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:17:21 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:17:21 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:21 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:17:21 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:21 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:17:22 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:22 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:17:23 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:23 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:17:24 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:26 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:28 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:30 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:32 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:34 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:34 DEBUG : ~leading tilde: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEsuliC8Xb_QYTen6OCvl6o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3988b80), FileID:"VOsEsulKC8Xb_QYTen6OCvl4o2", FileName:"~leading tilde", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x23a4c180, ext:63913979839, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x24e530c0, ext:63913979839, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:17:36 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:17:38 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:17:40 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:17:40 DEBUG : ~leading tilde: 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:"VOsEsuliC8Xb_QYTen6OCvl6o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3988b80), FileID:"VOsEsulKC8Xb_QYTen6OCvl4o2", FileName:"~leading tilde", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x23a4c180, ext:63913979839, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x24e530c0, ext:63913979839, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/leading_tilde Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:708: testing "␍leading CR" 2026/05/10 03:17:41 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:17:42 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:17:42 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:17:42 DEBUG : ␍leading CR/␍leading CR: calculating gcid: failed to unwrap object from src: ␍leading CR/␍leading CR 2026/05/10 03:17:42 DEBUG : ␍leading CR/␍leading CR: gcid = 94df131fe228ce7f0a62296a706c02bd2d96c687 2026/05/10 03:17:43 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:17:44 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:17:45 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:45 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:17:45 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:45 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:17:46 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:46 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:17:46 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:46 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:17:48 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:50 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:52 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:54 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:56 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:58 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:17:58 DEBUG : ␍leading CR: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEt-ZzqW4r6n2NPGHFjUcgo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d39892c0), FileID:"VOsEt-ZMqW4r6n2NPGHFjUcao2", FileName:"␍leading CR", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1565e7c0, ext:63913979863, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x16a65700, ext:63913979863, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:18:00 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:18:02 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:18:03 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:18:03 DEBUG : ␍leading 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:"VOsEt-ZzqW4r6n2NPGHFjUcgo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d39892c0), FileID:"VOsEt-ZMqW4r6n2NPGHFjUcao2", FileName:"␍leading CR", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1565e7c0, ext:63913979863, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x16a65700, ext:63913979863, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/leading_CR Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:708: testing "␊leading LF" 2026/05/10 03:18:05 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:18:05 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:18:06 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:18:06 DEBUG : ␊leading LF/␊leading LF: calculating gcid: failed to unwrap object from src: ␊leading LF/␊leading LF 2026/05/10 03:18:06 DEBUG : ␊leading LF/␊leading LF: gcid = 4c87268ead0d55a16191538fcf170b91c778247d 2026/05/10 03:18:07 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:18:08 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:18:09 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:09 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:18:09 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:09 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:18:09 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:09 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:18:10 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:10 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:18:12 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:14 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:16 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:18 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:20 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:22 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:22 DEBUG : ␊leading LF: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEt5LC0L_iSjvZ6dR5fOc9o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3696480), FileID:"VOsEt5Kp0L_iSjvZ6dR5fOc6o2", FileName:"␊leading LF", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x3af2f140, ext:63913979886, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0xe4e1c0, ext:63913979887, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:18:24 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:18:26 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:18:27 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:18:27 DEBUG : ␊leading 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:"VOsEt5LC0L_iSjvZ6dR5fOc9o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3696480), FileID:"VOsEt5Kp0L_iSjvZ6dR5fOc6o2", FileName:"␊leading LF", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x3af2f140, ext:63913979886, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0xe4e1c0, ext:63913979887, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/leading_LF Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:708: testing "␉leading HT" 2026/05/10 03:18:28 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:18:29 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:18:30 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:18:30 DEBUG : ␉leading HT/␉leading HT: calculating gcid: failed to unwrap object from src: ␉leading HT/␉leading HT 2026/05/10 03:18:30 DEBUG : ␉leading HT/␉leading HT: gcid = a042ce7db6818cf67430cd4766df3071e939cc0e 2026/05/10 03:18:30 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:18:32 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:18:32 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:32 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:18:33 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:33 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:18:33 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:33 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:18:34 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:34 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:18:35 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:37 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:39 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:41 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:43 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:45 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:45 DEBUG : ␉leading HT: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEtB9QlYLADoMYgilGIRyTo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d38fda80), FileID:"VOsEtB94lYLADoMYgilGIRyRo2", FileName:"␉leading HT", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x3056e0c0, ext:63913979910, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x31975000, ext:63913979910, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:18:47 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:18:49 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:18:51 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:18:51 DEBUG : ␉leading 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:"VOsEtB9QlYLADoMYgilGIRyTo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d38fda80), FileID:"VOsEtB94lYLADoMYgilGIRyRo2", FileName:"␉leading HT", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x3056e0c0, ext:63913979910, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x31975000, ext:63913979910, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/leading_HT Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:708: testing "␋leading VT" 2026/05/10 03:18:52 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:18:53 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:18:54 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:18:54 DEBUG : ␋leading VT/␋leading VT: calculating gcid: failed to unwrap object from src: ␋leading VT/␋leading VT 2026/05/10 03:18:54 DEBUG : ␋leading VT/␋leading VT: gcid = b366070a1763f889e3e25516a87be9b60a379e68 2026/05/10 03:18:54 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:18:55 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:18:56 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:56 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:18:56 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:56 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:18:57 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:57 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:18:58 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:18:58 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:18:59 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:01 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:03 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:05 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:07 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:09 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:09 DEBUG : ␋leading VT: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEtGwILSahb5zuLhSgFPsOo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3696540), FileID:"VOsEtGvvLSahb5zuLhSgFPsMo2", FileName:"␋leading VT", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1cc9fec0, ext:63913979934, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x1dfb2bc0, ext:63913979934, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:19:11 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:19:13 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:19:15 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:19:15 DEBUG : ␋leading 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:"VOsEtGwILSahb5zuLhSgFPsOo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3696540), FileID:"VOsEtGvvLSahb5zuLhSgFPsMo2", FileName:"␋leading VT", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x1cc9fec0, ext:63913979934, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x1dfb2bc0, ext:63913979934, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsEncoding/leading_VT Messages: Put === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:708: testing ".leading dot" 2026/05/10 03:19:16 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:19:17 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:19:17 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:19:17 DEBUG : .leading dot/.leading dot: calculating gcid: failed to unwrap object from src: .leading dot/.leading dot 2026/05/10 03:19:17 DEBUG : .leading dot/.leading dot: gcid = d5efeafdee6eac63697d8f5729f292a510c5586c 2026/05/10 03:19:18 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:19:19 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:19:20 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:20 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:19:20 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:20 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:19:20 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:20 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:19:21 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:21 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:19:23 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:25 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:27 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:29 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:31 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:33 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:33 DEBUG : .leading dot: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEtMhy-OxrCAYcLDGqbq0lo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3988c00), FileID:"VOsEtMhc-OxrCAYcLDGqbq0jo2", FileName:".leading dot", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x876bf80, ext:63913979958, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x9f437c0, ext:63913979958, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:19:35 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:19:37 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:19:38 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:19:38 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:"VOsEtMhy-OxrCAYcLDGqbq0lo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3988c00), FileID:"VOsEtMhc-OxrCAYcLDGqbq0jo2", FileName:".leading dot", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x876bf80, ext:63913979958, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x9f437c0, ext:63913979958, loc:(*time.Location)(0x36b2d3910e00)}, 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/10 03:19:39 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:19:40 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:19:41 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:19:41 DEBUG : trailing space /trailing space : calculating gcid: failed to unwrap object from src: trailing space /trailing space 2026/05/10 03:19:41 DEBUG : trailing space /trailing space : gcid = c621b934c9e93ab19da63d102559285d1f7c8934 2026/05/10 03:19:41 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:19:43 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:19:43 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:43 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:19:44 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:44 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:19:44 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:44 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:19:45 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:45 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:19:46 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:48 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:50 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:52 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:54 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:56 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:19:56 DEBUG : trailing space : canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEtSTnySgp_jifiaHHNCJmo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3988240), FileID:"VOsEtSTTySgp_jifiaHHNCJho2", FileName:"trailing space␠", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x2c8650c0, ext:63913979981, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x2db77dc0, ext:63913979981, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:19:58 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:20:00 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:20:02 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:20:02 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:"VOsEtSTnySgp_jifiaHHNCJmo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3988240), FileID:"VOsEtSTTySgp_jifiaHHNCJho2", FileName:"trailing space␠", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x2c8650c0, ext:63913979981, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x2db77dc0, ext:63913979981, loc:(*time.Location)(0x36b2d3910e00)}, 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/10 03:20:03 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:20:04 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:20:05 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:20:05 DEBUG : trailing CR␍/trailing CR␍: calculating gcid: failed to unwrap object from src: trailing CR␍/trailing CR␍ 2026/05/10 03:20:05 DEBUG : trailing CR␍/trailing CR␍: gcid = 776038147217370b63dec1e041ff6244c391a929 2026/05/10 03:20:05 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:20:06 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:20:07 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:07 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:20:07 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:07 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:20:08 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:08 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:20:08 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:08 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:20:10 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:12 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:14 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:16 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:18 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:20 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:20 DEBUG : trailing CR␍: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEtYFaTXN5L5GxxIb38V4ho2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3988940), FileID:"VOsEtYFDTXN5L5GxxIb38V4do2", FileName:"trailing CR␍", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x18ad2380, ext:63913980005, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x1a67a4c0, ext:63913980005, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:20:22 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:20:24 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:20:26 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:20:26 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:"VOsEtYFaTXN5L5GxxIb38V4ho2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3988940), FileID:"VOsEtYFDTXN5L5GxxIb38V4do2", FileName:"trailing CR␍", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x18ad2380, ext:63913980005, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x1a67a4c0, ext:63913980005, loc:(*time.Location)(0x36b2d3910e00)}, 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/10 03:20:27 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:20:28 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:20:28 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:20:28 DEBUG : trailing LF␊/trailing LF␊: calculating gcid: failed to unwrap object from src: trailing LF␊/trailing LF␊ 2026/05/10 03:20:28 DEBUG : trailing LF␊/trailing LF␊: gcid = f1c6eb039bcd336f84ae7a2181ef0f671cb75843 2026/05/10 03:20:29 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:20:30 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:20:31 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:31 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:20:31 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:31 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:20:31 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:31 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:20:32 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:32 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:20:34 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:36 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:38 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:40 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:42 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:44 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:44 DEBUG : trailing LF␊: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEtd1q-OxrCAYcLDGqbtxio2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3946440), FileID:"VOsEtd1U-OxrCAYcLDGqbtxgo2", FileName:"trailing LF␊", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x68e7780, ext:63913980029, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x8a48640, ext:63913980029, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:20:46 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:20:48 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:20:49 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:20:49 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:"VOsEtd1q-OxrCAYcLDGqbtxio2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3946440), FileID:"VOsEtd1U-OxrCAYcLDGqbtxgo2", FileName:"trailing LF␊", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x68e7780, ext:63913980029, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x8a48640, ext:63913980029, loc:(*time.Location)(0x36b2d3910e00)}, 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/10 03:20:51 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:20:51 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:20:52 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:20:52 DEBUG : trailing HT␉/trailing HT␉: calculating gcid: failed to unwrap object from src: trailing HT␉/trailing HT␉ 2026/05/10 03:20:52 DEBUG : trailing HT␉/trailing HT␉: gcid = 414bf78c02ffab4ec7b036ee15a46eb94b0bbc92 2026/05/10 03:20:53 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:20:54 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:20:54 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:54 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:20:55 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:55 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:20:55 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:55 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:20:56 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:56 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:20:57 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:20:59 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:01 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:03 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:06 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2026/05/10 03:21:06 DEBUG : Config file has changed externally - reloading 2026/05/10 03:21:06 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:07 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:07 DEBUG : trailing HT␉: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEtiqalYLADoMYgilGIX8Io2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d34fb6c0), FileID:"VOsEtiqDlYLADoMYgilGIX85o2", FileName:"trailing HT␉", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x35c36d80, ext:63913980052, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x375f6a40, ext:63913980052, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:21:09 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:21:11 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:21:13 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:21:13 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:"VOsEtiqalYLADoMYgilGIX8Io2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d34fb6c0), FileID:"VOsEtiqDlYLADoMYgilGIX85o2", FileName:"trailing HT␉", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x35c36d80, ext:63913980052, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x375f6a40, ext:63913980052, loc:(*time.Location)(0x36b2d3910e00)}, 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/10 03:21:14 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:21:15 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:21:16 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:21:16 DEBUG : trailing VT␋/trailing VT␋: calculating gcid: failed to unwrap object from src: trailing VT␋/trailing VT␋ 2026/05/10 03:21:16 DEBUG : trailing VT␋/trailing VT␋: gcid = 4552ddf7447f9bbc96c2ae13075f7b51c91c26fd 2026/05/10 03:21:16 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:21:18 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:21:18 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:18 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:21:19 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:19 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:21:19 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:19 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:21:20 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:20 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:21:21 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:23 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:25 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:27 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:29 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:31 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:31 DEBUG : trailing VT␋: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEtocg-OxrCAYcLDGqbvzAo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3989340), FileID:"VOsEtobs-OxrCAYcLDGqbvz8o2", FileName:"trailing VT␋", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x230c2b00, ext:63913980076, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x29b92700, ext:63913980076, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:21:33 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:21:35 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:21:37 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:21:37 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:"VOsEtocg-OxrCAYcLDGqbvzAo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3989340), FileID:"VOsEtobs-OxrCAYcLDGqbvz8o2", FileName:"trailing VT␋", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x230c2b00, ext:63913980076, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x29b92700, ext:63913980076, loc:(*time.Location)(0x36b2d3910e00)}, 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/10 03:21:38 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:21:39 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:21:39 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:21:39 DEBUG : trailing dot./trailing dot.: calculating gcid: failed to unwrap object from src: trailing dot./trailing dot. 2026/05/10 03:21:39 DEBUG : trailing dot./trailing dot.: gcid = 784180b9a44904bde0fd192a0737b1bed303e273 2026/05/10 03:21:40 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:21:41 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:21:42 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:42 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:21:42 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:42 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:21:43 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:43 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:21:43 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:43 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:21:45 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:47 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:49 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:51 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:53 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:55 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:21:55 DEBUG : trailing dot.: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEtuQc0L_iSjvZ6dR5fgf5o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3872680), FileID:"VOsEtuQL0L_iSjvZ6dR5fgf1o2", FileName:"trailing dot.", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x138ce200, ext:63913980100, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x149f8a80, ext:63913980100, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:21:57 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:21:59 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:22:01 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:22:01 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:"VOsEtuQc0L_iSjvZ6dR5fgf5o2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3872680), FileID:"VOsEtuQL0L_iSjvZ6dR5fgf1o2", FileName:"trailing dot.", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x138ce200, ext:63913980100, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x149f8a80, ext:63913980100, loc:(*time.Location)(0x36b2d3910e00)}, 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/10 03:22:02 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:22:03 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:22:03 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:22:03 DEBUG : invalid utf-8/invalid utf-8: calculating gcid: failed to unwrap object from src: invalid utf-8/invalid utf-8 2026/05/10 03:22:03 DEBUG : invalid utf-8/invalid utf-8: gcid = 83a2dadc1181e62b9e65a33bd6fe181ded14e2dc 2026/05/10 03:22:04 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:22:05 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:22:06 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:06 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:22:06 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:06 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:22:06 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:06 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:22:07 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:07 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:22:09 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:11 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:13 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:15 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:17 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:19 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:19 DEBUG : invalid utf-8: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEu-DtlYLADoMYgilGI_dqo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3988680), FileID:"VOsEu-DdlYLADoMYgilGI_dno2", FileName:"invalid utf-8‛FE", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x54e0840, ext:63913980124, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x68e7780, ext:63913980124, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:22:21 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:22:23 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:22:24 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:22:24 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:"VOsEu-DtlYLADoMYgilGI_dqo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3988680), FileID:"VOsEu-DdlYLADoMYgilGI_dno2", FileName:"invalid utf-8‛FE", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x54e0840, ext:63913980124, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x68e7780, ext:63913980124, loc:(*time.Location)(0x36b2d3910e00)}, 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/10 03:22:26 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:22:26 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:22:27 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:22:27 DEBUG : test%46.txt/test%46.txt: calculating gcid: failed to unwrap object from src: test%46.txt/test%46.txt 2026/05/10 03:22:27 DEBUG : test%46.txt/test%46.txt: gcid = 6957d65b27c3b341dedc2ad57c79a31d37cec277 2026/05/10 03:22:27 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/05/10 03:22:29 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/05/10 03:22:29 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:29 DEBUG : pacer: Rate limited, increasing sleep to 400.451658ms 2026/05/10 03:22:29 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:29 DEBUG : pacer: Rate limited, increasing sleep to 800.903316ms 2026/05/10 03:22:30 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:30 DEBUG : pacer: Rate limited, increasing sleep to 1.601806632s 2026/05/10 03:22:31 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:31 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:22:32 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:34 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:36 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:38 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:40 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:42 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:42 DEBUG : test%46.txt: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEu5-scn7Ku0VP0fjc706Ko2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d38fd500), FileID:"VOsEu5-Tcn7Ku0VP0fjc706Eo2", FileName:"test%46.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x2dc6c000, ext:63913980147, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x2f072f40, ext:63913980147, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:22:44 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:22:46 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:22:48 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:22:48 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:"VOsEu5-scn7Ku0VP0fjc706Ko2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d38fd500), FileID:"VOsEu5-Tcn7Ku0VP0fjc706Eo2", FileName:"test%46.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x2dc6c000, ext:63913980147, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0x2f072f40, ext:63913980147, loc:(*time.Location)(0x36b2d3910e00)}, 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/10 03:22:49 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:22:49 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2026/05/10 03:22:49 DEBUG : file name.txt: gcid = a9092f5f28b58c1202c841d1475f66e11606cd79 2026/05/10 03:22:50 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:22:51 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:22:52 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:52 DEBUG : pacer: Rate limited, increasing sleep to 711.914062ms 2026/05/10 03:22:53 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:53 DEBUG : pacer: Rate limited, increasing sleep to 1.423828124s 2026/05/10 03:22:53 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:53 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:22:55 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:57 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:22:59 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:23:01 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:23:03 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:23:05 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:23:07 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2026/05/10 03:23:07 DEBUG : file name.txt: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsEuAUG-OxrCAYcLDGqc2rdo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3872a40), FileID:"VOsEuAU0-OxrCAYcLDGqc2rbo2", FileName:"file name.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0xb80f240, ext:63913980170, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0xca2dd00, ext:63913980170, loc:(*time.Location)(0x36b2d3910e00)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0d8b20b308a8f2fb094caba73cd2b809f7f3c636", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2026/05/10 03:23:09 DEBUG : pacer: Reducing sleep to 1.5s 2026/05/10 03:23:11 DEBUG : pacer: Reducing sleep to 1.125s 2026/05/10 03:23:12 DEBUG : pacer: Reducing sleep to 843.75ms 2026/05/10 03:23:12 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:"VOsEuAUG-OxrCAYcLDGqc2rdo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x36b2d3872a40), FileID:"VOsEuAU0-OxrCAYcLDGqc2rbo2", FileName:"file name.txt", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0xb80f240, ext:63913980170, loc:(*time.Location)(0x36b2d3910e00)}, UpdatedTime:api.Time{wall:0xca2dd00, ext:63913980170, loc:(*time.Location)(0x36b2d3910e00)}, 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/10 03:23:13 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/05/10 03:23:14 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/05/10 03:23:15 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/05/10 03:23:15 ERROR : error listing: directory not found 2026/05/10 03:23:15 DEBUG : pacer: Reducing sleep to 266.967773ms --- FAIL: TestIntegration (428.44s) --- FAIL: TestIntegration/FsMkdir (426.62s) --- FAIL: TestIntegration/FsMkdir/FsEncoding (399.45s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/dot_dot (20.22s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/punctuation (23.70s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_space (23.64s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_tilde (23.70s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_CR (23.66s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_LF (23.81s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_HT (23.69s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_VT (23.66s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_dot (23.60s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_space (23.67s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_CR (23.69s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_LF (23.79s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_HT (23.57s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_VT (23.87s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_dot (23.76s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (23.69s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/URL_encoding (23.52s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles (24.40s) FAIL exit status 1 FAIL github.com/rclone/rclone/backend/pikpak 428.456s "go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|dot_dot|invalid_UTF-8|leading_CR|leading_HT|leading_LF|leading_VT|leading_dot|leading_space|leading_tilde|punctuation|trailing_CR|trailing_HT|trailing_LF|trailing_VT|trailing_dot|trailing_space)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$'" - Finished ERROR in 7m9.621094977s (try 5/5): exit status 1: Failed [TestIntegration/FsMkdir/FsEncoding/dot_dot TestIntegration/FsMkdir/FsEncoding/punctuation TestIntegration/FsMkdir/FsEncoding/leading_space TestIntegration/FsMkdir/FsEncoding/leading_tilde TestIntegration/FsMkdir/FsEncoding/leading_CR TestIntegration/FsMkdir/FsEncoding/leading_LF TestIntegration/FsMkdir/FsEncoding/leading_HT TestIntegration/FsMkdir/FsEncoding/leading_VT 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]