"go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|control_chars|dot|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$/^(FsDirMove|FsListDirFile2|FsListLevel2|FsListR|FsPurge|FsPurgeRoot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries)$'" - Starting (try 2/5) === RUN TestIntegration fstests.go:442: Using remote "TestPikPak:" 2024/11/14 21:21:14 DEBUG : Creating backend with remote "TestPikPak:rclone-test-negifuj2faba" 2024/11/14 21:21:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:711: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2024/11/14 21:21:17 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: calculating gcid: failed to unwrap object from src: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡ 2024/11/14 21:21:17 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: gcid = 0237c5c2d848f4cb9efbf626b151800ff0c463a2 2024/11/14 21:21:22 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Rmdir: contains trashed file: "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:711: testing "．" 2024/11/14 21:21:24 DEBUG : ．/．: calculating gcid: failed to unwrap object from src: ．/． 2024/11/14 21:21:24 DEBUG : ．/．: gcid = 9151f984efd05bb58fde671439de837141b9f73d 2024/11/14 21:21:26 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:26 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/14 21:21:27 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:27 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/14 21:21:27 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:27 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/14 21:21:27 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:27 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/14 21:21:28 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:28 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:21:30 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:21:32 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:21:33 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:21:34 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:21:35 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/14 21:21:36 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/14 21:21:36 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/14 21:21:36 DEBUG : ．: Rmdir: contains trashed file: "．" 2024/11/14 21:21:37 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/14 21:21:38 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/14 21:21:38 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:711: testing "．．" 2024/11/14 21:21:38 DEBUG : pacer: Reducing sleep to 100ms 2024/11/14 21:21:39 DEBUG : ．．/．．: calculating gcid: failed to unwrap object from src: ．．/．． 2024/11/14 21:21:39 DEBUG : ．．/．．: gcid = 1f6541bd23f921cd896781e1c58c2e37d609d8c2 2024/11/14 21:21:43 DEBUG : ．．: Rmdir: contains trashed file: "．．" === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:711: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2024/11/14 21:21:45 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: calculating gcid: failed to unwrap object from src: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~ 2024/11/14 21:21:45 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: gcid = 7535f5d6f48cf44b7fe98ee30a13f3688fef9fc9 2024/11/14 21:21:47 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:47 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/14 21:21:48 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:48 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/14 21:21:48 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:48 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/14 21:21:48 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:48 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/14 21:21:49 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:49 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:21:51 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:53 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:55 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:57 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2024/11/14 21:21:57 DEBUG : Config file has changed externally - reloading 2024/11/14 21:21:57 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:59 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:21:59 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOBgYQ_5UtRgzDIuCjbumPSYo1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc00029f380), FileID:"VOBgYQZvUtRgzDIuCjbumPSVo1", FileName:"!＂#$%&'()＊+,-.／：;＜=＞？@[＼]^_`{｜}~", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x2f908380, ext:63867216105, loc:(*time.Location)(0xc0001649a0)}, UpdatedTime:api.Time{wall:0x30ef7740, ext:63867216105, loc:(*time.Location)(0xc0001649a0)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2024/11/14 21:22:01 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:22:03 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:22:04 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:22:04 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: waiting 500ms for the cancellation to be effective fstests.go:145: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:161 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:223 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:213 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:717 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOBgYQ_5UtRgzDIuCjbumPSYo1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc00029f380), FileID:"VOBgYQZvUtRgzDIuCjbumPSVo1", FileName:"!＂#$%&'()＊+,-.／：;＜=＞？@[＼]^_`{｜}~", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x2f908380, ext:63867216105, loc:(*time.Location)(0xc0001649a0)}, UpdatedTime:api.Time{wall:0x30ef7740, ext:63867216105, loc:(*time.Location)(0xc0001649a0)}, 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:711: testing " leading space" 2024/11/14 21:22:05 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:22:06 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/14 21:22:07 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/14 21:22:07 DEBUG : leading space/ leading space: calculating gcid: failed to unwrap object from src: leading space/ leading space 2024/11/14 21:22:07 DEBUG : leading space/ leading space: gcid = 25e59ed938b8f8fac6a08e7582698442cc74c252 2024/11/14 21:22:07 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/14 21:22:09 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/14 21:22:09 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/14 21:22:10 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/11/14 21:22:10 DEBUG : pacer: Reducing sleep to 100ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{" leading space"} actual : []string{" leading space", "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"} Diff: --- Expected +++ Actual @@ -1,3 +1,4 @@ -([]string) (len=1) { - (string) (len=14) " leading space" +([]string) (len=2) { + (string) (len=14) " leading space", + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" } Test: TestIntegration/FsMkdir/FsEncoding/leading_space Messages: directories 2024/11/14 21:22:22 DEBUG : leading space: Rmdir: contains trashed file: " leading space" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" } Test: TestIntegration/FsMkdir/FsEncoding/leading_space Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:711: testing "~leading tilde" 2024/11/14 21:22:33 DEBUG : ~leading tilde/~leading tilde: calculating gcid: failed to unwrap object from src: ~leading tilde/~leading tilde 2024/11/14 21:22:33 DEBUG : ~leading tilde/~leading tilde: gcid = adf1ff84f7b59a561098b44bef634cc370c8c0ba fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"~leading tilde"} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "~leading tilde"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", (string) (len=14) "~leading tilde" Test: TestIntegration/FsMkdir/FsEncoding/leading_tilde Messages: directories 2024/11/14 21:22:47 DEBUG : ~leading tilde: Rmdir: contains trashed file: "~leading tilde" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" } Test: TestIntegration/FsMkdir/FsEncoding/leading_tilde Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:711: testing "␍leading CR" 2024/11/14 21:22:58 DEBUG : ␍leading CR/␍leading CR: calculating gcid: failed to unwrap object from src: ␍leading CR/␍leading CR 2024/11/14 21:22:58 DEBUG : ␍leading CR/␍leading CR: gcid = 342bbc4ed69eedee72cc307a62b7ad53bdda4042 fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"␍leading CR"} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "␍leading CR"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", (string) (len=13) "␍leading CR" Test: TestIntegration/FsMkdir/FsEncoding/leading_CR Messages: directories 2024/11/14 21:23:13 DEBUG : ␍leading CR: Rmdir: contains trashed file: "␍leading CR" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" } Test: TestIntegration/FsMkdir/FsEncoding/leading_CR Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:711: testing "␊leading LF" 2024/11/14 21:23:23 DEBUG : ␊leading LF/␊leading LF: calculating gcid: failed to unwrap object from src: ␊leading LF/␊leading LF 2024/11/14 21:23:23 DEBUG : ␊leading LF/␊leading LF: gcid = a0993c730270fae2652e14e3abb6cf95f98804c6 fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"␊leading LF"} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "␊leading LF"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", (string) (len=13) "␊leading LF" Test: TestIntegration/FsMkdir/FsEncoding/leading_LF Messages: directories 2024/11/14 21:23:38 DEBUG : ␊leading LF: Rmdir: contains trashed file: "␊leading LF" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" } Test: TestIntegration/FsMkdir/FsEncoding/leading_LF Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:711: testing "␉leading HT" 2024/11/14 21:23:49 DEBUG : ␉leading HT/␉leading HT: calculating gcid: failed to unwrap object from src: ␉leading HT/␉leading HT 2024/11/14 21:23:49 DEBUG : ␉leading HT/␉leading HT: gcid = b94ac067e5b4e57acc8b78521ae5d4ec2de4b537 2024/11/14 21:23:52 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:23:52 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/14 21:23:52 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:23:52 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/14 21:23:52 DEBUG : pacer: Reducing sleep to 300ms 2024/11/14 21:23:53 DEBUG : pacer: Reducing sleep to 225ms 2024/11/14 21:23:53 DEBUG : pacer: Reducing sleep to 168.75ms 2024/11/14 21:23:53 DEBUG : pacer: Reducing sleep to 126.5625ms 2024/11/14 21:23:54 DEBUG : pacer: Reducing sleep to 100ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"␉leading HT"} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "␉leading HT"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", (string) (len=13) "␉leading HT" Test: TestIntegration/FsMkdir/FsEncoding/leading_HT Messages: directories 2024/11/14 21:24:05 DEBUG : ␉leading HT: Rmdir: contains trashed file: "␉leading HT" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" } Test: TestIntegration/FsMkdir/FsEncoding/leading_HT Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:711: testing "␋leading VT" 2024/11/14 21:24:15 DEBUG : ␋leading VT/␋leading VT: calculating gcid: failed to unwrap object from src: ␋leading VT/␋leading VT 2024/11/14 21:24:15 DEBUG : ␋leading VT/␋leading VT: gcid = 7e3e332fb2cbbb39e6e3779b8ca793796a20cb57 2024/11/14 21:24:18 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:24:18 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/14 21:24:18 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:24:18 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/14 21:24:18 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:24:18 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/14 21:24:19 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:24:19 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/14 21:24:19 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:24:19 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:24:21 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:24:23 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:24:24 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:24:26 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:24:26 DEBUG : pacer: Reducing sleep to 474.609375ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/11/14 21:24:28 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/14 21:24:28 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/14 21:24:29 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/14 21:24:29 DEBUG : pacer: Reducing sleep to 150.169371ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/11/14 21:24:31 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/11/14 21:24:31 DEBUG : pacer: Reducing sleep to 100ms fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"␋leading VT"} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "␋leading VT"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", (string) (len=13) "␋leading VT" Test: TestIntegration/FsMkdir/FsEncoding/leading_VT Messages: directories 2024/11/14 21:24:38 DEBUG : ␋leading VT: Rmdir: contains trashed file: "␋leading VT" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" } Test: TestIntegration/FsMkdir/FsEncoding/leading_VT Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:711: testing ".leading dot" 2024/11/14 21:24:49 DEBUG : .leading dot/.leading dot: calculating gcid: failed to unwrap object from src: .leading dot/.leading dot 2024/11/14 21:24:49 DEBUG : .leading dot/.leading dot: gcid = 5368a329ba18d7aa807bbcea0e4675880eb7bb7d 2024/11/14 21:24:51 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:24:51 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/14 21:24:51 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:24:51 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/14 21:24:52 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:24:52 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/14 21:24:52 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:24:52 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/14 21:24:53 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:24:53 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:24:54 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:24:57 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:24:58 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:24:59 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:25:00 DEBUG : pacer: Reducing sleep to 474.609375ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/11/14 21:25:01 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/14 21:25:02 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/14 21:25:02 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/14 21:25:02 DEBUG : pacer: Reducing sleep to 150.169371ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/11/14 21:25:05 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/11/14 21:25:05 DEBUG : pacer: Reducing sleep to 100ms fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{".leading dot"} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", ".leading dot"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", (string) (len=12) ".leading dot" Test: TestIntegration/FsMkdir/FsEncoding/leading_dot Messages: directories 2024/11/14 21:25:12 DEBUG : .leading dot: Rmdir: contains trashed file: ".leading dot" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" } Test: TestIntegration/FsMkdir/FsEncoding/leading_dot Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:711: testing "trailing space " 2024/11/14 21:25:23 DEBUG : trailing space /trailing space : calculating gcid: failed to unwrap object from src: trailing space /trailing space 2024/11/14 21:25:23 DEBUG : trailing space /trailing space : gcid = 680d39889e451a6c10ff90d65705ac7005d5199d 2024/11/14 21:25:26 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:26 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/14 21:25:26 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:26 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/14 21:25:26 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:26 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/14 21:25:27 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:27 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/14 21:25:27 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:27 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:25:29 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:31 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:33 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:35 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:37 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:37 DEBUG : trailing space : canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOBgZFmIpm4xgrXdN8Cp8S54o1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc000172ac0), FileID:"VOBgZFm6pm4xgrXdN8Cp8S52o1", FileName:"trailing space␠", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x2b922cc0, ext:63867216323, loc:(*time.Location)(0xc0001649a0)}, UpdatedTime:api.Time{wall:0x2cf12080, ext:63867216323, loc:(*time.Location)(0xc0001649a0)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2024/11/14 21:25:39 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:25:41 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:25:42 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:25:42 DEBUG : trailing space : waiting 500ms for the cancellation to be effective fstests.go:145: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:161 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:223 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:213 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:717 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOBgZFmIpm4xgrXdN8Cp8S54o1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc000172ac0), FileID:"VOBgZFm6pm4xgrXdN8Cp8S52o1", FileName:"trailing space␠", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x2b922cc0, ext:63867216323, loc:(*time.Location)(0xc0001649a0)}, UpdatedTime:api.Time{wall:0x2cf12080, ext:63867216323, loc:(*time.Location)(0xc0001649a0)}, 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:711: testing "trailing CR␍" 2024/11/14 21:25:44 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:25:44 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/14 21:25:45 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/14 21:25:45 DEBUG : trailing CR␍/trailing CR␍: calculating gcid: failed to unwrap object from src: trailing CR␍/trailing CR␍ 2024/11/14 21:25:45 DEBUG : trailing CR␍/trailing CR␍: gcid = 080662b474b5f70073d5097ad409c887631b70ad 2024/11/14 21:25:46 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/14 21:25:47 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:47 DEBUG : pacer: Rate limited, increasing sleep to 533.935546ms 2024/11/14 21:25:48 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:48 DEBUG : pacer: Rate limited, increasing sleep to 1.067871092s 2024/11/14 21:25:48 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:48 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:25:49 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:51 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:25:53 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:25:55 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:25:57 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:25:58 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:25:59 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/14 21:25:59 DEBUG : pacer: Reducing sleep to 355.957031ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/11/14 21:26:01 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/14 21:26:01 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/14 21:26:01 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/14 21:26:02 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/11/14 21:26:02 DEBUG : pacer: Reducing sleep to 100ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"trailing CR␍"} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "trailing CR␍", "trailing space "} Diff: --- Expected +++ Actual @@ -1,3 +1,5 @@ -([]string) (len=1) { - (string) (len=14) "trailing CR␍" +([]string) (len=3) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=14) "trailing CR␍", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/trailing_CR Messages: directories 2024/11/14 21:26:12 DEBUG : trailing CR␍: Rmdir: contains trashed file: "trailing CR␍" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "trailing space "} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/trailing_CR Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:711: testing "trailing LF␊" 2024/11/14 21:26:24 DEBUG : trailing LF␊/trailing LF␊: calculating gcid: failed to unwrap object from src: trailing LF␊/trailing LF␊ 2024/11/14 21:26:24 DEBUG : trailing LF␊/trailing LF␊: gcid = 7e137a7ee4cfc7ad99710aae3a490e64ec755e81 2024/11/14 21:26:26 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:26:26 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/14 21:26:26 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:26:26 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/14 21:26:27 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:26:27 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/14 21:26:27 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:26:27 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/14 21:26:28 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:26:28 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:26:29 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:26:31 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:26:33 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:26:35 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:26:36 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:26:37 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/14 21:26:38 DEBUG : pacer: Reducing sleep to 355.957031ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/11/14 21:26:39 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/14 21:26:39 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/14 21:26:40 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/14 21:26:40 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/11/14 21:26:40 DEBUG : pacer: Reducing sleep to 100ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"trailing LF␊"} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "trailing LF␊", "trailing space "} Diff: --- Expected +++ Actual @@ -1,3 +1,5 @@ -([]string) (len=1) { - (string) (len=14) "trailing LF␊" +([]string) (len=3) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=14) "trailing LF␊", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/trailing_LF Messages: directories 2024/11/14 21:26:50 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2024/11/14 21:26:50 DEBUG : Config file has changed externally - reloading 2024/11/14 21:26:52 DEBUG : trailing LF␊: Rmdir: contains trashed file: "trailing LF␊" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "trailing space "} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/trailing_LF Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:711: testing "trailing HT␉" 2024/11/14 21:27:04 DEBUG : trailing HT␉/trailing HT␉: calculating gcid: failed to unwrap object from src: trailing HT␉/trailing HT␉ 2024/11/14 21:27:04 DEBUG : trailing HT␉/trailing HT␉: gcid = c87c4bca04f2f362d64b3f017b7542ff590795ed 2024/11/14 21:27:07 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:27:07 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/14 21:27:07 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:27:07 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/14 21:27:07 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:27:07 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/14 21:27:08 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:27:08 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/14 21:27:08 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:27:08 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:27:10 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:27:12 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:27:14 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:27:15 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:27:16 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/14 21:27:17 DEBUG : pacer: Reducing sleep to 355.957031ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/11/14 21:27:18 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/14 21:27:18 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/14 21:27:19 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/14 21:27:19 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/11/14 21:27:19 DEBUG : pacer: Reducing sleep to 100ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"trailing HT␉"} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "trailing HT␉", "trailing space "} Diff: --- Expected +++ Actual @@ -1,3 +1,5 @@ -([]string) (len=1) { - (string) (len=14) "trailing HT␉" +([]string) (len=3) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=14) "trailing HT␉", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/trailing_HT Messages: directories 2024/11/14 21:27:29 DEBUG : trailing HT␉: Rmdir: contains trashed file: "trailing HT␉" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "trailing space "} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/trailing_HT Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:711: testing "trailing VT␋" 2024/11/14 21:27:40 DEBUG : trailing VT␋/trailing VT␋: calculating gcid: failed to unwrap object from src: trailing VT␋/trailing VT␋ 2024/11/14 21:27:40 DEBUG : trailing VT␋/trailing VT␋: gcid = 30251cb8f74ed80738a2c663f9967d3b9a86de81 2024/11/14 21:27:43 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:27:43 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/14 21:27:43 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:27:43 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/14 21:27:43 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:27:43 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/14 21:27:44 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:27:44 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/14 21:27:44 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:27:44 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:27:46 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:27:48 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:27:50 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:27:51 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:27:51 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/14 21:27:52 DEBUG : pacer: Reducing sleep to 355.957031ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/11/14 21:27:53 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/14 21:27:54 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/14 21:27:54 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/14 21:27:54 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/11/14 21:27:55 DEBUG : pacer: Reducing sleep to 100ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"trailing VT␋"} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "trailing VT␋", "trailing space "} Diff: --- Expected +++ Actual @@ -1,3 +1,5 @@ -([]string) (len=1) { - (string) (len=14) "trailing VT␋" +([]string) (len=3) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=14) "trailing VT␋", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/trailing_VT Messages: directories 2024/11/14 21:28:05 DEBUG : trailing VT␋: Rmdir: contains trashed file: "trailing VT␋" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "trailing space "} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/trailing_VT Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:711: testing "trailing dot." 2024/11/14 21:28:16 DEBUG : trailing dot./trailing dot.: calculating gcid: failed to unwrap object from src: trailing dot./trailing dot. 2024/11/14 21:28:16 DEBUG : trailing dot./trailing dot.: gcid = 06bb56ba84159bb2f67ad121228f52a45bf05fd1 2024/11/14 21:28:19 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:28:19 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/14 21:28:19 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:28:19 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/14 21:28:19 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:28:19 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/14 21:28:20 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:28:20 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/14 21:28:20 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:28:20 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:28:22 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:28:24 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:28:26 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:28:28 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:28:29 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:28:31 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:28:31 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/14 21:28:32 DEBUG : pacer: Reducing sleep to 355.957031ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/11/14 21:28:33 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/14 21:28:34 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/14 21:28:34 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/14 21:28:34 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/11/14 21:28:35 DEBUG : pacer: Reducing sleep to 100ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"trailing dot."} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "trailing dot.", "trailing space "} Diff: --- Expected +++ Actual @@ -1,3 +1,5 @@ -([]string) (len=1) { - (string) (len=13) "trailing dot." +([]string) (len=3) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=13) "trailing dot.", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/trailing_dot Messages: directories 2024/11/14 21:28:44 DEBUG : trailing dot.: Rmdir: contains trashed file: "trailing dot." fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "trailing space "} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/trailing_dot Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:711: testing "invalid utf-8\xfe" 2024/11/14 21:28:55 DEBUG : invalid utf-8/invalid utf-8: calculating gcid: failed to unwrap object from src: invalid utf-8/invalid utf-8 2024/11/14 21:28:55 DEBUG : invalid utf-8/invalid utf-8: gcid = bf73f99368a0ae82970fd226018d2a2323668a85 fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"invalid utf-8\xfe"} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "invalid utf-8\xfe", "trailing space "} Diff: --- Expected +++ Actual @@ -1,3 +1,5 @@ -([]string) (len=1) { - (string) (len=14) "invalid utf-8\xfe" +([]string) (len=3) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=14) "invalid utf-8\xfe", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 Messages: directories 2024/11/14 21:29:10 DEBUG : invalid utf-8: Rmdir: contains trashed file: "invalid utf-8\xfe" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "trailing space "} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:711: testing "test%46.txt" 2024/11/14 21:29:21 DEBUG : test%46.txt/test%46.txt: calculating gcid: failed to unwrap object from src: test%46.txt/test%46.txt 2024/11/14 21:29:21 DEBUG : test%46.txt/test%46.txt: gcid = 207417945c473f12e26b21259074a0485a9ff11c fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"test%46.txt"} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "test%46.txt", "trailing space "} Diff: --- Expected +++ Actual @@ -1,3 +1,5 @@ -([]string) (len=1) { - (string) (len=11) "test%46.txt" +([]string) (len=3) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=11) "test%46.txt", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/URL_encoding Messages: directories 2024/11/14 21:29:36 DEBUG : test%46.txt: Rmdir: contains trashed file: "test%46.txt" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{"!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", "trailing space "} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=34) "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~", + (string) (len=15) "trailing space " } Test: TestIntegration/FsMkdir/FsEncoding/URL_encoding Messages: directories === RUN TestIntegration/FsMkdir/FsPutFiles 2024/11/14 21:29:47 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2024/11/14 21:29:47 DEBUG : file name.txt: gcid = 4db84526df7a2ecfe308adacf53ad84b32010a9a 2024/11/14 21:29:49 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:29:49 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/14 21:29:49 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:29:49 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/14 21:29:50 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:29:50 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/14 21:29:50 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:29:50 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/14 21:29:51 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:29:51 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:29:52 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:29:54 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:29:56 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:29:58 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:29:59 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:30:00 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/14 21:30:01 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/14 21:30:01 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/11/14 21:30:01 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/11/14 21:30:02 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/11/14 21:30:02 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/11/14 21:30:02 DEBUG : pacer: Reducing sleep to 100ms 2024/11/14 21:30:02 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: calculating gcid: failed to unwrap object from src: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt 2024/11/14 21:30:02 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: gcid = 81e8c525f6d2df61a7194655dbae362baaf69f51 2024/11/14 21:30:04 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:04 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2024/11/14 21:30:05 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:05 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2024/11/14 21:30:05 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:05 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2024/11/14 21:30:05 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:05 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2024/11/14 21:30:06 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:06 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:30:08 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:10 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:12 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:30:14 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:30:16 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:30:16 DEBUG : file name.txt: calculating gcid: failed to unwrap object from src: file name.txt 2024/11/14 21:30:16 DEBUG : file name.txt: gcid = 97a6bd8913395c353470ee286f38ff71f412b13a 2024/11/14 21:30:16 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:30:18 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:18 DEBUG : pacer: Rate limited, increasing sleep to 1.265625s 2024/11/14 21:30:19 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:19 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/11/14 21:30:20 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:22 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:24 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:26 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:28 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:30 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:32 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:34 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING) 2024/11/14 21:30:34 DEBUG : rcloneTemprafixit2: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOBg_NIPtFR84lQ30ulcDftHo1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc000098100), FileID:"VOBg_NI8tFR84lQ30ulcDftFo1", FileName:"rcloneTemprafixit2", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x27b25a80, ext:63867216616, loc:(*time.Location)(0xc0001649a0)}, UpdatedTime:api.Time{wall:0x29114e40, ext:63867216616, loc:(*time.Location)(0xc0001649a0)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2024/11/14 21:30:36 DEBUG : pacer: Reducing sleep to 1.5s 2024/11/14 21:30:38 DEBUG : pacer: Reducing sleep to 1.125s 2024/11/14 21:30:40 DEBUG : pacer: Reducing sleep to 843.75ms 2024/11/14 21:30:40 DEBUG : rcloneTemprafixit2: waiting 500ms for the cancellation to be effective fstests.go:145: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:161 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:223 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:959 Error: Received unexpected error: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOBg_NIPtFR84lQ30ulcDftHo1", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc000098100), FileID:"VOBg_NI8tFR84lQ30ulcDftFo1", FileName:"rcloneTemprafixit2", FileSize:"100", Message:"Pending", CreatedTime:api.Time{wall:0x27b25a80, ext:63867216616, loc:(*time.Location)(0xc0001649a0)}, UpdatedTime:api.Time{wall:0x29114e40, ext:63867216616, loc:(*time.Location)(0xc0001649a0)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestIntegration/FsMkdir/FsPutFiles Messages: Put 2024/11/14 21:30:41 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/11/14 21:30:42 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/11/14 21:30:42 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/11/14 21:30:42 ERROR : : error listing: directory not found 2024/11/14 21:30:43 DEBUG : pacer: Reducing sleep to 266.967773ms --- FAIL: TestIntegration (569.08s) --- FAIL: TestIntegration/FsMkdir (567.03s) --- FAIL: TestIntegration/FsMkdir/FsEncoding (510.21s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (7.13s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (14.45s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (6.46s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/punctuation (20.35s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_space (27.19s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_tilde (25.41s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_CR (25.18s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_LF (25.66s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_HT (26.37s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_VT (33.47s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_dot (34.03s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_space (20.96s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_CR (39.52s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_LF (40.50s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_HT (36.24s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_VT (35.94s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_dot (39.27s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (25.93s) --- FAIL: TestIntegration/FsMkdir/FsEncoding/URL_encoding (25.89s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles (54.00s) FAIL exit status 1 FAIL github.com/rclone/rclone/backend/pikpak 569.089s "go test -v -timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsEncoding$/^(URL_encoding|control_chars|dot|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$/^(FsDirMove|FsListDirFile2|FsListLevel2|FsListR|FsPurge|FsPurgeRoot)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^FromRoot$/^(List|ListEntries)$'" - Finished ERROR in 9m31.172954143s (try 2/5): exit status 1: Failed [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]