"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024" - Starting (try 1/5)
=== RUN   TestIntegration
    fstests.go:418: Using remote "TestChunkerChunk50bYandex:"
2021/06/01 05:09:40 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-ceziyal9rafopas2talupij8"
2021/06/01 05:09:40 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2021/06/01 05:09:40 DEBUG : Creating backend with remote "TestYandex:rclone-test-ceziyal9rafopas2talupij8"
2021/06/01 05:09:41 DEBUG : Reset feature "ListR"
=== RUN   TestIntegration/FsCheckWrap
=== RUN   TestIntegration/FsCommand
    fstests.go:483: No commands in this remote
=== RUN   TestIntegration/FsRmdirNotFound
=== RUN   TestIntegration/FsString
=== RUN   TestIntegration/FsName
=== RUN   TestIntegration/FsRoot
=== RUN   TestIntegration/FsRmdirEmpty
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsMkdirRmdirSubdir
2021/06/01 05:09:44 DEBUG : dir/subdir: Making directory
2021/06/01 05:09:46 DEBUG : dir/subdir: Removing directory
2021/06/01 05:09:48 DEBUG : dir: Removing directory
=== RUN   TestIntegration/FsMkdir/FsListEmpty
=== RUN   TestIntegration/FsMkdir/FsListDirEmpty
=== RUN   TestIntegration/FsMkdir/FsListRDirEmpty
    fstests.go:378: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsListDirNotFound
2021/06/01 05:09:50 ERROR : does not exist: error listing: directory not found
=== RUN   TestIntegration/FsMkdir/FsListRDirNotFound
    fstests.go:378: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsEncoding
=== RUN   TestIntegration/FsMkdir/FsEncoding/control_chars
    fstests.go:669: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡"
2021/06/01 05:09:58 INFO  : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡.rclone_chunk.001_0gw9o5: Moved (server-side) to: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡.rclone_chunk.001
2021/06/01 05:10:00 INFO  : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡.rclone_chunk.002_0gw9o5: Moved (server-side) to: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/dot
    fstests.go:669: testing "．"
2021/06/01 05:10:18 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:10:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:10:18 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:10:19 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:10:19 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:10:21 INFO  : ．/．.rclone_chunk.001_0gwvqc: Moved (server-side) to: ．/．.rclone_chunk.001
2021/06/01 05:10:22 INFO  : ．/．.rclone_chunk.002_0gwvqc: Moved (server-side) to: ．/．.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/dot_dot
    fstests.go:669: testing "．．"
2021/06/01 05:10:44 INFO  : ．．/．．.rclone_chunk.001_0gxi71: Moved (server-side) to: ．．/．．.rclone_chunk.001
2021/06/01 05:10:45 INFO  : ．．/．．.rclone_chunk.002_0gxi71: Moved (server-side) to: ．．/．．.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/punctuation
    fstests.go:669: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"
2021/06/01 05:11:02 INFO  : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~.rclone_chunk.001_0gy1ob: Moved (server-side) to: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~.rclone_chunk.001
2021/06/01 05:11:03 INFO  : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~.rclone_chunk.002_0gy1ob: Moved (server-side) to: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_space
    fstests.go:669: testing " leading space"
2021/06/01 05:11:18 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:11:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:11:19 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:11:19 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:11:19 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:11:21 INFO  :  leading space/ leading space.rclone_chunk.001_0gyjbk: Moved (server-side) to:  leading space/ leading space.rclone_chunk.001
2021/06/01 05:11:23 INFO  :  leading space/ leading space.rclone_chunk.002_0gyjbk: Moved (server-side) to:  leading space/ leading space.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_tilde
    fstests.go:669: testing "~leading tilde"
2021/06/01 05:11:37 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:11:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:11:37 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:11:37 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:11:38 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:11:42 INFO  : ~leading tilde/~leading tilde.rclone_chunk.001_0gz4ug: Moved (server-side) to: ~leading tilde/~leading tilde.rclone_chunk.001
2021/06/01 05:11:43 INFO  : ~leading tilde/~leading tilde.rclone_chunk.002_0gz4ug: Moved (server-side) to: ~leading tilde/~leading tilde.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_CR
    fstests.go:669: testing "␍leading CR"
2021/06/01 05:11:58 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:11:58 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:11:58 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:11:59 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:11:59 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:12:01 INFO  : ␍leading CR/␍leading CR.rclone_chunk.001_0gznk1: Moved (server-side) to: ␍leading CR/␍leading CR.rclone_chunk.001
2021/06/01 05:12:02 INFO  : ␍leading CR/␍leading CR.rclone_chunk.002_0gznk1: Moved (server-side) to: ␍leading CR/␍leading CR.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_LF
    fstests.go:669: testing "␊leading LF"
2021/06/01 05:12:19 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:12:19 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:12:20 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:12:20 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:12:20 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:12:22 INFO  : ␊leading LF/␊leading LF.rclone_chunk.001_0h087c: Moved (server-side) to: ␊leading LF/␊leading LF.rclone_chunk.001
2021/06/01 05:12:23 INFO  : ␊leading LF/␊leading LF.rclone_chunk.002_0h087c: Moved (server-side) to: ␊leading LF/␊leading LF.rclone_chunk.002
    fstest.go:128: 
        	Error Trace:	fstest.go:128
        	            				fstest.go:133
        	            				fstest.go:152
        	            				fstests.go:202
        	            				fstests.go:212
        	            				fstests.go:217
        	            				fstests.go:675
        	Error:      	Should be true
        	Test:       	TestIntegration/FsMkdir/FsEncoding/leading_LF
        	Messages:   	␊leading LF/␊leading LF: Modification time difference too big |-10.478968946s| > 1ns (want 2021-06-01 05:12:14.521031054 +0000 UTC m=+153.554932006 vs got 2021-06-01 05:12:25 +0000 UTC) (precision 1ns)
    fstest.go:128: 
        	Error Trace:	fstest.go:128
        	            				fstest.go:133
        	            				fstest.go:152
        	            				fstests.go:205
        	            				fstests.go:212
        	            				fstests.go:217
        	            				fstests.go:675
        	Error:      	Should be true
        	Test:       	TestIntegration/FsMkdir/FsEncoding/leading_LF
        	Messages:   	␊leading LF/␊leading LF: Modification time difference too big |-10.478968946s| > 1ns (want 2021-06-01 05:12:14.521031054 +0000 UTC m=+153.554932006 vs got 2021-06-01 05:12:25 +0000 UTC) (precision 1ns)
    fstest.go:128: 
        	Error Trace:	fstest.go:128
        	            				fstest.go:133
        	            				fstest.go:152
        	            				fstest.go:196
        	            				fstest.go:309
        	            				fstest.go:339
        	            				fstests.go:676
        	Error:      	Should be true
        	Test:       	TestIntegration/FsMkdir/FsEncoding/leading_LF
        	Messages:   	␊leading LF/␊leading LF: Modification time difference too big |-10.478968946s| > 1ns (want 2021-06-01 05:12:14.521031054 +0000 UTC m=+153.554932006 vs got 2021-06-01 05:12:25 +0000 UTC) (precision 1ns)
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_HT
    fstests.go:669: testing "␉leading HT"
2021/06/01 05:12:43 INFO  : ␉leading HT/␉leading HT.rclone_chunk.001_0h0s2h: Moved (server-side) to: ␉leading HT/␉leading HT.rclone_chunk.001
2021/06/01 05:12:44 INFO  : ␉leading HT/␉leading HT.rclone_chunk.002_0h0s2h: Moved (server-side) to: ␉leading HT/␉leading HT.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_VT
    fstests.go:669: testing "␋leading VT"
2021/06/01 05:13:01 INFO  : ␋leading VT/␋leading VT.rclone_chunk.001_0h1cj8: Moved (server-side) to: ␋leading VT/␋leading VT.rclone_chunk.001
2021/06/01 05:13:02 INFO  : ␋leading VT/␋leading VT.rclone_chunk.002_0h1cj8: Moved (server-side) to: ␋leading VT/␋leading VT.rclone_chunk.002
2021/06/01 05:13:05 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:13:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:13:05 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:13:06 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:13:06 DEBUG : pacer: Reducing sleep to 10ms
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_dot
    fstests.go:669: testing ".leading dot"
2021/06/01 05:13:29 INFO  : .leading dot/.leading dot.rclone_chunk.001_0h1vx4: Moved (server-side) to: .leading dot/.leading dot.rclone_chunk.001
2021/06/01 05:13:30 INFO  : .leading dot/.leading dot.rclone_chunk.002_0h1vx4: Moved (server-side) to: .leading dot/.leading dot.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_space
    fstests.go:669: testing "trailing space "
2021/06/01 05:13:47 INFO  : trailing space /trailing space .rclone_chunk.001_0h2mdw: Moved (server-side) to: trailing space /trailing space .rclone_chunk.001
2021/06/01 05:13:48 INFO  : trailing space /trailing space .rclone_chunk.002_0h2mdw: Moved (server-side) to: trailing space /trailing space .rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_CR
    fstests.go:669: testing "trailing CR␍"
2021/06/01 05:14:05 INFO  : trailing CR␍/trailing CR␍.rclone_chunk.001_0h34ha: Moved (server-side) to: trailing CR␍/trailing CR␍.rclone_chunk.001
2021/06/01 05:14:06 INFO  : trailing CR␍/trailing CR␍.rclone_chunk.002_0h34ha: Moved (server-side) to: trailing CR␍/trailing CR␍.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_LF
    fstests.go:669: testing "trailing LF␊"
2021/06/01 05:14:25 INFO  : trailing LF␊/trailing LF␊.rclone_chunk.001_0h3nz2: Moved (server-side) to: trailing LF␊/trailing LF␊.rclone_chunk.001
2021/06/01 05:14:27 INFO  : trailing LF␊/trailing LF␊.rclone_chunk.002_0h3nz2: Moved (server-side) to: trailing LF␊/trailing LF␊.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_HT
    fstests.go:669: testing "trailing HT␉"
2021/06/01 05:14:47 INFO  : trailing HT␉/trailing HT␉.rclone_chunk.001_0h4a74: Moved (server-side) to: trailing HT␉/trailing HT␉.rclone_chunk.001
2021/06/01 05:14:48 INFO  : trailing HT␉/trailing HT␉.rclone_chunk.002_0h4a74: Moved (server-side) to: trailing HT␉/trailing HT␉.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_VT
    fstests.go:669: testing "trailing VT␋"
2021/06/01 05:15:08 INFO  : trailing VT␋/trailing VT␋.rclone_chunk.001_0h4tt9: Moved (server-side) to: trailing VT␋/trailing VT␋.rclone_chunk.001
2021/06/01 05:15:13 INFO  : trailing VT␋/trailing VT␋.rclone_chunk.002_0h4tt9: Moved (server-side) to: trailing VT␋/trailing VT␋.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_dot
    fstests.go:669: testing "trailing dot."
2021/06/01 05:15:29 INFO  : trailing dot./trailing dot..rclone_chunk.001_0h5hy1: Moved (server-side) to: trailing dot./trailing dot..rclone_chunk.001
2021/06/01 05:15:31 INFO  : trailing dot./trailing dot..rclone_chunk.002_0h5hy1: Moved (server-side) to: trailing dot./trailing dot..rclone_chunk.002
    fstest.go:128: 
        	Error Trace:	fstest.go:128
        	            				fstest.go:133
        	            				fstest.go:152
        	            				fstests.go:202
        	            				fstests.go:212
        	            				fstests.go:217
        	            				fstests.go:675
        	Error:      	Should be true
        	Test:       	TestIntegration/FsMkdir/FsEncoding/trailing_dot
        	Messages:   	trailing dot./trailing dot.: Modification time difference too big |-9.201996405s| > 1ns (want 2021-06-01 05:15:22.798003595 +0000 UTC m=+341.831904557 vs got 2021-06-01 05:15:32 +0000 UTC) (precision 1ns)
    fstest.go:128: 
        	Error Trace:	fstest.go:128
        	            				fstest.go:133
        	            				fstest.go:152
        	            				fstests.go:205
        	            				fstests.go:212
        	            				fstests.go:217
        	            				fstests.go:675
        	Error:      	Should be true
        	Test:       	TestIntegration/FsMkdir/FsEncoding/trailing_dot
        	Messages:   	trailing dot./trailing dot.: Modification time difference too big |-9.201996405s| > 1ns (want 2021-06-01 05:15:22.798003595 +0000 UTC m=+341.831904557 vs got 2021-06-01 05:15:32 +0000 UTC) (precision 1ns)
    fstest.go:128: 
        	Error Trace:	fstest.go:128
        	            				fstest.go:133
        	            				fstest.go:152
        	            				fstest.go:196
        	            				fstest.go:309
        	            				fstest.go:339
        	            				fstests.go:676
        	Error:      	Should be true
        	Test:       	TestIntegration/FsMkdir/FsEncoding/trailing_dot
        	Messages:   	trailing dot./trailing dot.: Modification time difference too big |-9.201996405s| > 1ns (want 2021-06-01 05:15:22.798003595 +0000 UTC m=+341.831904557 vs got 2021-06-01 05:15:32 +0000 UTC) (precision 1ns)
=== RUN   TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8
    fstests.go:669: testing "invalid utf-8\xfe"
2021/06/01 05:15:50 INFO  : invalid utf-8�/invalid utf-8�.rclone_chunk.001_0h5zlo: Moved (server-side) to: invalid utf-8�/invalid utf-8�.rclone_chunk.001
2021/06/01 05:15:51 INFO  : invalid utf-8�/invalid utf-8�.rclone_chunk.002_0h5zlo: Moved (server-side) to: invalid utf-8�/invalid utf-8�.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsNewObjectNotFound
=== RUN   TestIntegration/FsMkdir/FsPutError
    fstests.go:709: Reduce file size due to limit 1024
2021/06/01 05:16:16 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:16:16 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:16:17 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:16:17 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:16:18 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:16:52 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:16:52 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:16:53 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:16:53 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:16:54 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:16:58 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:16:58 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:16:58 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:16:59 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:16:59 DEBUG : pacer: Reducing sleep to 10ms
=== RUN   TestIntegration/FsMkdir/FsPutZeroLength
2021/06/01 05:17:29 INFO  : zero-length-file.rclone_chunk.001_0h8vk6: Moved (server-side) to: zero-length-file
=== RUN   TestIntegration/FsMkdir/FsOpenWriterAt
    fstests.go:742: FS has no OpenWriterAt interface
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
    fstests.go:778: FS has no ChangeNotify interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles
2021/06/01 05:17:36 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:17:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:17:37 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:17:37 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:17:37 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:17:39 INFO  : file name.txt.rclone_chunk.001_0h9128: Moved (server-side) to: file name.txt.rclone_chunk.001
2021/06/01 05:17:40 INFO  : file name.txt.rclone_chunk.002_0h9128: Moved (server-side) to: file name.txt.rclone_chunk.002
2021/06/01 05:17:54 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:17:54 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:17:54 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:17:54 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:17:55 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:17:59 INFO  : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.001_0h9lqr: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.001
2021/06/01 05:18:01 INFO  : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.002_0h9lqr: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.002
2021/06/01 05:18:06 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:18:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:18:06 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:18:07 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:18:07 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:18:20 INFO  : file name.txt.rclone_chunk.001_0ha35s: Moved (server-side) to: file name.txt.rclone_chunk.001
2021/06/01 05:18:21 INFO  : file name.txt.rclone_chunk.002_0ha35s: Moved (server-side) to: file name.txt.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2
    fstests.go:378: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListR
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot
2021/06/01 05:18:29 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:"
2021/06/01 05:18:29 DEBUG : Creating backend with remote "TestYandex:"
2021/06/01 05:18:29 DEBUG : Reset feature "ListR"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot
    fstests.go:378: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListSubdir
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01
    fstests.go:378: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListLevel2
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2
    fstests.go:378: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListFile1
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsNewObject
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive
    fstests.go:1031: Not Case Insensitive
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPurge
2021/06/01 05:18:45 INFO  : dirToPurge/fileToPurge.txt.rclone_chunk.001_0havl7: Moved (server-side) to: dirToPurge/fileToPurge.txt.rclone_chunk.001
2021/06/01 05:18:48 INFO  : dirToPurge/fileToPurge.txt.rclone_chunk.002_0havl7: Moved (server-side) to: dirToPurge/fileToPurge.txt.rclone_chunk.002
2021/06/01 05:18:50 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:18:50 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:18:51 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:18:51 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:18:51 DEBUG : pacer: Reducing sleep to 10ms
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsCopy
2021/06/01 05:19:01 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: copy 2 data chunks...
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsMove
2021/06/01 05:19:14 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: move 2 data chunks...
2021/06/01 05:19:16 INFO  : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.001: Moved (server-side) to: other.txt.rclone_chunk.001
2021/06/01 05:19:17 INFO  : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.002: Moved (server-side) to: other.txt.rclone_chunk.002
2021/06/01 05:19:19 INFO  : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Moved (server-side) to: other.txt
2021/06/01 05:19:25 DEBUG : file name.txt: move 2 data chunks...
2021/06/01 05:19:27 INFO  : file name.txt.rclone_chunk.001: Moved (server-side) to: moveTest/other.txt.rclone_chunk.001
2021/06/01 05:19:28 INFO  : file name.txt.rclone_chunk.002: Moved (server-side) to: moveTest/other.txt.rclone_chunk.002
2021/06/01 05:19:29 INFO  : file name.txt: Moved (server-side) to: moveTest/other.txt
2021/06/01 05:19:35 DEBUG : moveTest/other.txt: move 2 data chunks...
2021/06/01 05:19:36 INFO  : moveTest/other.txt.rclone_chunk.001: Moved (server-side) to: file name.txt.rclone_chunk.001
2021/06/01 05:19:38 INFO  : moveTest/other.txt.rclone_chunk.002: Moved (server-side) to: file name.txt.rclone_chunk.002
2021/06/01 05:19:39 INFO  : moveTest/other.txt: Moved (server-side) to: file name.txt
2021/06/01 05:19:45 DEBUG : other.txt: move 2 data chunks...
2021/06/01 05:19:46 INFO  : other.txt.rclone_chunk.001: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.001
2021/06/01 05:19:47 INFO  : other.txt.rclone_chunk.002: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.002
2021/06/01 05:19:49 INFO  : other.txt: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsDirMove
2021/06/01 05:19:54 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-tefedum6lotodug9gipifal7"
2021/06/01 05:19:54 DEBUG : Creating backend with remote "TestYandex:rclone-test-tefedum6lotodug9gipifal7"
2021/06/01 05:19:54 DEBUG : Reset feature "ListR"
2021/06/01 05:20:10 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-tefedum6lotodug9gipifal7': Purge remote
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPrecision
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectString
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectFs
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectRemote
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectHashes
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectModTime
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType
    fstests.go:1338: MimeType method not supported
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectSize
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectOpen
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate
2021/06/01 05:20:41 INFO  : file name.txt.rclone_chunk.001_0hdyem: Moved (server-side) to: file name.txt.rclone_chunk.001
2021/06/01 05:20:43 INFO  : file name.txt.rclone_chunk.002_0hdyem: Moved (server-side) to: file name.txt.rclone_chunk.002
2021/06/01 05:20:44 INFO  : file name.txt.rclone_chunk.003_0hdyem: Moved (server-side) to: file name.txt.rclone_chunk.003
2021/06/01 05:20:45 INFO  : file name.txt.rclone_chunk.004_0hdyem: Moved (server-side) to: file name.txt.rclone_chunk.004
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectStorable
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsIsFile
2021/06/01 05:20:52 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-ceziyal9rafopas2talupij8/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt"
2021/06/01 05:20:52 DEBUG : Creating backend with remote "TestYandex:rclone-test-ceziyal9rafopas2talupij8/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt"
2021/06/01 05:20:52 DEBUG : fs cache: adding new entry for parent of "TestYandex:rclone-test-ceziyal9rafopas2talupij8/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt", "TestYandex:rclone-test-ceziyal9rafopas2talupij8/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠"
2021/06/01 05:20:52 DEBUG : Reset feature "ListR"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound
2021/06/01 05:20:53 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-ceziyal9rafopas2talupij8/not found.txt"
2021/06/01 05:20:53 DEBUG : Creating backend with remote "TestYandex:rclone-test-ceziyal9rafopas2talupij8/not found.txt"
2021/06/01 05:20:53 DEBUG : Reset feature "ListR"
2021/06/01 05:20:53 ERROR : : error listing: directory not found
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot
    fstests.go:1496: Opening root remote "TestChunkerChunk50bYandex:" path "rclone-test-ceziyal9rafopas2talupij8" from "TestChunkerChunk50bYandex:rclone-test-ceziyal9rafopas2talupij8"
2021/06/01 05:20:53 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:"
2021/06/01 05:20:53 DEBUG : Reset feature "ListR"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/List
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR
    fstests.go:1530: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put
2021/06/01 05:21:03 INFO  : rclone-test-ceziyal9rafopas2talupij8/created from root.txt.rclone_chunk.001_0heqbe: Moved (server-side) to: rclone-test-ceziyal9rafopas2talupij8/created from root.txt.rclone_chunk.001
2021/06/01 05:21:04 INFO  : rclone-test-ceziyal9rafopas2talupij8/created from root.txt.rclone_chunk.002_0heqbe: Moved (server-side) to: rclone-test-ceziyal9rafopas2talupij8/created from root.txt.rclone_chunk.002
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove
=== RUN   TestIntegration/FsMkdir/FsPutFiles/PublicLink
    fstests.go:1589: FS has no PublicLinker interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/SetTier
    fstests.go:392: FS has no SetTier & GetTier interfaces
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectRemove
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectAbout
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPutStream
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0
2021/06/01 05:21:29 INFO  : piped data.txt.rclone_chunk.001_0hfjrv: Moved (server-side) to: piped data.txt
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100
2021/06/01 05:21:39 INFO  : piped data.txt.rclone_chunk.001_0hfpb1: Moved (server-side) to: piped data.txt.rclone_chunk.001
2021/06/01 05:21:40 INFO  : piped data.txt.rclone_chunk.002_0hfpb1: Moved (server-side) to: piped data.txt.rclone_chunk.002
2021/06/01 05:21:42 INFO  : piped data.txt.rclone_chunk.003_0hfpb1: Moved (server-side) to: piped data.txt.rclone_chunk.003
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/PutLarge
    chunker_internal_test.go:851: -upload-kilobytes is not set
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkNameFormat
2021/06/01 05:21:53 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-ceziyal9rafopas2talupij8': invalid data chunk number in file "fish.chunk.001"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/SmallFileInternals
2021/06/01 05:21:57 INFO  : small/emptyfile.rclone_chunk.001_0hgbn7: Moved (server-side) to: small/emptyfile
2021/06/01 05:22:03 INFO  : small/smallfile.rclone_chunk.001_0hgh2h: Moved (server-side) to: small/smallfile
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/PreventCorruption
2021/06/01 05:22:35 INFO  : corrupted/billy.rclone_chunk.001_0hgp4p: Moved (server-side) to: corrupted/billy.rclone_chunk.001
2021/06/01 05:22:37 INFO  : corrupted/billy.rclone_chunk.002_0hgp4p: Moved (server-side) to: corrupted/billy.rclone_chunk.002
2021/06/01 05:22:38 INFO  : corrupted/billy.rclone_chunk.003_0hgp4p: Moved (server-side) to: corrupted/billy.rclone_chunk.003
2021/06/01 05:22:39 INFO  : corrupted/billy.rclone_chunk.004_0hgp4p: Moved (server-side) to: corrupted/billy.rclone_chunk.004
2021/06/01 05:22:41 INFO  : corrupted/billy.rclone_chunk.005_0hgp4p: Moved (server-side) to: corrupted/billy.rclone_chunk.005
2021/06/01 05:23:01 INFO  : corrupted/silly1.rclone_chunk.001_0hhszf: Moved (server-side) to: corrupted/silly1.rclone_chunk.001
2021/06/01 05:23:02 INFO  : corrupted/silly1.rclone_chunk.002_0hhszf: Moved (server-side) to: corrupted/silly1.rclone_chunk.002
2021/06/01 05:23:03 INFO  : corrupted/silly1.rclone_chunk.003_0hhszf: Moved (server-side) to: corrupted/silly1.rclone_chunk.003
2021/06/01 05:23:05 INFO  : corrupted/silly1.rclone_chunk.004_0hhszf: Moved (server-side) to: corrupted/silly1.rclone_chunk.004
2021/06/01 05:23:06 INFO  : corrupted/silly1.rclone_chunk.005_0hhszf: Moved (server-side) to: corrupted/silly1.rclone_chunk.005
2021/06/01 05:23:22 INFO  : corrupted/silly2.rclone_chunk.001_0higuw: Moved (server-side) to: corrupted/silly2.rclone_chunk.001
2021/06/01 05:23:23 INFO  : corrupted/silly2.rclone_chunk.002_0higuw: Moved (server-side) to: corrupted/silly2.rclone_chunk.002
2021/06/01 05:23:24 INFO  : corrupted/silly2.rclone_chunk.003_0higuw: Moved (server-side) to: corrupted/silly2.rclone_chunk.003
2021/06/01 05:23:26 INFO  : corrupted/silly2.rclone_chunk.004_0higuw: Moved (server-side) to: corrupted/silly2.rclone_chunk.004
2021/06/01 05:23:30 INFO  : corrupted/silly2.rclone_chunk.005_0higuw: Moved (server-side) to: corrupted/silly2.rclone_chunk.005
2021/06/01 05:23:49 INFO  : corrupted/silly3.rclone_chunk.001_0hj597: Moved (server-side) to: corrupted/silly3.rclone_chunk.001
2021/06/01 05:23:50 INFO  : corrupted/silly3.rclone_chunk.002_0hj597: Moved (server-side) to: corrupted/silly3.rclone_chunk.002
2021/06/01 05:23:52 INFO  : corrupted/silly3.rclone_chunk.003_0hj597: Moved (server-side) to: corrupted/silly3.rclone_chunk.003
2021/06/01 05:23:53 INFO  : corrupted/silly3.rclone_chunk.004_0hj597: Moved (server-side) to: corrupted/silly3.rclone_chunk.004
2021/06/01 05:23:54 INFO  : corrupted/silly3.rclone_chunk.005_0hj597: Moved (server-side) to: corrupted/silly3.rclone_chunk.005
2021/06/01 05:24:12 INFO  : corrupted/willy.rclone_chunk.001_0hjsws: Moved (server-side) to: corrupted/willy.rclone_chunk.001
2021/06/01 05:24:13 INFO  : corrupted/willy.rclone_chunk.002_0hjsws: Moved (server-side) to: corrupted/willy.rclone_chunk.002
2021/06/01 05:24:14 INFO  : corrupted/willy.rclone_chunk.003_0hjsws: Moved (server-side) to: corrupted/willy.rclone_chunk.003
2021/06/01 05:24:15 INFO  : corrupted/willy.rclone_chunk.004_0hjsws: Moved (server-side) to: corrupted/willy.rclone_chunk.004
2021/06/01 05:24:17 INFO  : corrupted/willy.rclone_chunk.005_0hjsws: Moved (server-side) to: corrupted/willy.rclone_chunk.005
2021/06/01 05:24:37 INFO  : corrupted/silly4.rclone_chunk.001_0hkfqi: Moved (server-side) to: corrupted/silly4.rclone_chunk.001
2021/06/01 05:24:38 INFO  : corrupted/silly4.rclone_chunk.002_0hkfqi: Moved (server-side) to: corrupted/silly4.rclone_chunk.002
2021/06/01 05:24:39 INFO  : corrupted/silly4.rclone_chunk.003_0hkfqi: Moved (server-side) to: corrupted/silly4.rclone_chunk.003
2021/06/01 05:24:41 INFO  : corrupted/silly4.rclone_chunk.004_0hkfqi: Moved (server-side) to: corrupted/silly4.rclone_chunk.004
2021/06/01 05:24:42 INFO  : corrupted/silly4.rclone_chunk.005_0hkfqi: Moved (server-side) to: corrupted/silly4.rclone_chunk.005
2021/06/01 05:24:45 ERROR : corrupted/silly4: Failed to copy: can't copy: chunk overlap with "corrupted/willy"
2021/06/01 05:24:58 INFO  : corrupted/silly5.rclone_chunk.001_0hl3e4: Moved (server-side) to: corrupted/silly5.rclone_chunk.001
2021/06/01 05:25:03 INFO  : corrupted/silly5.rclone_chunk.002_0hl3e4: Moved (server-side) to: corrupted/silly5.rclone_chunk.002
2021/06/01 05:25:04 INFO  : corrupted/silly5.rclone_chunk.003_0hl3e4: Moved (server-side) to: corrupted/silly5.rclone_chunk.003
2021/06/01 05:25:05 INFO  : corrupted/silly5.rclone_chunk.004_0hl3e4: Moved (server-side) to: corrupted/silly5.rclone_chunk.004
2021/06/01 05:25:06 INFO  : corrupted/silly5.rclone_chunk.005_0hl3e4: Moved (server-side) to: corrupted/silly5.rclone_chunk.005
2021/06/01 05:25:10 ERROR : corrupted/willy.rclone_chunk.002: Couldn't delete: refuse to corrupt: chunk overlap with "corrupted/willy"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkNumberOverflow
2021/06/01 05:25:26 INFO  : wreaked/wreaker.rclone_chunk.001_0hm14i: Moved (server-side) to: wreaked/wreaker.rclone_chunk.001
2021/06/01 05:25:27 INFO  : wreaked/wreaker.rclone_chunk.002_0hm14i: Moved (server-side) to: wreaked/wreaker.rclone_chunk.002
2021/06/01 05:25:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-ceziyal9rafopas2talupij8': invalid directory entry "wreaked/wreaker"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/MetadataInput
2021/06/01 05:25:47 ERROR : usermeta/today.rclone_chunk.001_0hmqqx: Couldn't move: couldn't move file: [409 - DiskResourceAlreadyExistsError] Resource "disk:/rclone-test-ceziyal9rafopas2talupij8/usermeta/today.rclone_chunk.001" already exists. (Ресурс "disk:/rclone-test-ceziyal9rafopas2talupij8/usermeta/today.rclone_chunk.001" уже существует.)
2021/06/01 05:25:49 INFO  : usermeta/today.rclone_chunk.001: Deleted
2021/06/01 05:25:50 INFO  : usermeta/today.rclone_chunk.001_0hmqqx: Moved (server-side) to: usermeta/today.rclone_chunk.001
2021/06/01 05:26:02 ERROR : usermeta/past.rclone_chunk.001_0hn5o9: Couldn't move: couldn't move file: [409 - DiskResourceAlreadyExistsError] Resource "disk:/rclone-test-ceziyal9rafopas2talupij8/usermeta/past.rclone_chunk.001" already exists. (Ресурс "disk:/rclone-test-ceziyal9rafopas2talupij8/usermeta/past.rclone_chunk.001" уже существует.)
2021/06/01 05:26:09 INFO  : usermeta/past.rclone_chunk.001: Deleted
2021/06/01 05:26:13 INFO  : usermeta/past.rclone_chunk.001_0hn5o9: Moved (server-side) to: usermeta/past.rclone_chunk.001
2021/06/01 05:26:29 ERROR : usermeta/future.rclone_chunk.001_0hnwpi: Couldn't move: couldn't move file: [409 - DiskResourceAlreadyExistsError] Resource "disk:/rclone-test-ceziyal9rafopas2talupij8/usermeta/future.rclone_chunk.001" already exists. (Ресурс "disk:/rclone-test-ceziyal9rafopas2talupij8/usermeta/future.rclone_chunk.001" уже существует.)
2021/06/01 05:26:31 INFO  : usermeta/future.rclone_chunk.001: Deleted
2021/06/01 05:26:32 INFO  : usermeta/future.rclone_chunk.001_0hnwpi: Moved (server-side) to: usermeta/future.rclone_chunk.001
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/FutureProof
2021/06/01 05:26:57 DEBUG : future/test: Can't move - required hash not found
2021/06/01 05:26:57 DEBUG : future/test: Can't move, switching to copy
2021/06/01 05:26:57 DEBUG : future/test: Can't copy - required hash not found
2021/06/01 05:26:57 ERROR : future/test: Failed to copy: failed to open source object: can't open: unknown metadata, please upgrade rclone
2021/06/01 05:26:57 ERROR : future/test: Not deleting source as copy failed: failed to open source object: can't open: unknown metadata, please upgrade rclone
2021/06/01 05:26:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-ceziyal9rafopas2talupij8': File to upload is small (3 bytes), uploading instead of streaming
2021/06/01 05:26:59 ERROR : future/test: Failed to copy: refusing to put: unknown metadata, please upgrade rclone
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/BackwardsCompatibility
2021/06/01 05:27:19 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:27:19 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:27:19 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:27:20 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:27:20 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:27:41 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:27:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:27:42 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:27:42 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:27:45 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:28:10 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:28:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:28:10 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:28:10 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:28:11 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:28:20 INFO  : backcomp/renamefile.rclone_chunk.001_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.001
2021/06/01 05:28:21 INFO  : backcomp/renamefile.rclone_chunk.002_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.002
2021/06/01 05:28:22 INFO  : backcomp/renamefile.rclone_chunk.003_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.003
2021/06/01 05:28:23 INFO  : backcomp/renamefile.rclone_chunk.004_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.004
2021/06/01 05:28:25 INFO  : backcomp/renamefile.rclone_chunk.005_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.005
2021/06/01 05:28:26 INFO  : backcomp/renamefile.rclone_chunk.006_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.006
2021/06/01 05:28:28 INFO  : backcomp/renamefile.rclone_chunk.007_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.007
2021/06/01 05:28:29 INFO  : backcomp/renamefile.rclone_chunk.008_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.008
2021/06/01 05:28:30 INFO  : backcomp/renamefile.rclone_chunk.009_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.009
2021/06/01 05:28:31 INFO  : backcomp/renamefile.rclone_chunk.010_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.010
2021/06/01 05:28:33 INFO  : backcomp/renamefile.rclone_chunk.011_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.011
2021/06/01 05:28:34 INFO  : backcomp/renamefile.rclone_chunk.012_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.012
2021/06/01 05:28:35 INFO  : backcomp/renamefile.rclone_chunk.013_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.013
2021/06/01 05:28:37 INFO  : backcomp/renamefile.rclone_chunk.014_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.014
2021/06/01 05:28:38 INFO  : backcomp/renamefile.rclone_chunk.015_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.015
2021/06/01 05:28:39 INFO  : backcomp/renamefile.rclone_chunk.016_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.016
2021/06/01 05:28:41 INFO  : backcomp/renamefile.rclone_chunk.017_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.017
2021/06/01 05:28:42 INFO  : backcomp/renamefile.rclone_chunk.018_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.018
2021/06/01 05:28:43 INFO  : backcomp/renamefile.rclone_chunk.019_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.019
2021/06/01 05:28:44 INFO  : backcomp/renamefile.rclone_chunk.020_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.020
2021/06/01 05:28:47 INFO  : backcomp/renamefile.rclone_chunk.021_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.021
2021/06/01 05:28:49 INFO  : backcomp/renamefile.rclone_chunk.022_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.022
2021/06/01 05:28:50 INFO  : backcomp/renamefile.rclone_chunk.023_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.023
2021/06/01 05:28:51 INFO  : backcomp/renamefile.rclone_chunk.024_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.024
2021/06/01 05:28:54 INFO  : backcomp/renamefile.rclone_chunk.025_0hoxue: Moved (server-side) to: backcomp/renamefile.rclone_chunk.025
2021/06/01 05:28:56 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:28:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:28:56 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:28:57 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:28:57 DEBUG : pacer: Reducing sleep to 10ms
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkerServerSideMove
2021/06/01 05:29:33 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-ceziyal9rafopas2talupij8/servermovetest/subdir1"
2021/06/01 05:29:33 DEBUG : Creating backend with remote "TestYandex:rclone-test-ceziyal9rafopas2talupij8/servermovetest/subdir1"
2021/06/01 05:29:33 DEBUG : Reset feature "ListR"
2021/06/01 05:29:33 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-ceziyal9rafopas2talupij8/servermovetest/subdir2"
2021/06/01 05:29:33 DEBUG : Creating backend with remote "TestYandex:rclone-test-ceziyal9rafopas2talupij8/servermovetest/subdir2"
2021/06/01 05:29:33 DEBUG : Reset feature "ListR"
2021/06/01 05:29:41 INFO  : movefile.rclone_chunk.001_0ht3lk: Moved (server-side) to: movefile.rclone_chunk.001
2021/06/01 05:29:42 INFO  : movefile.rclone_chunk.002_0ht3lk: Moved (server-side) to: movefile.rclone_chunk.002
2021/06/01 05:29:46 DEBUG : movefile: move 2 data chunks...
2021/06/01 05:29:48 INFO  : movefile.rclone_chunk.001: Moved (server-side)
2021/06/01 05:29:49 INFO  : movefile.rclone_chunk.002: Moved (server-side)
2021/06/01 05:29:51 INFO  : movefile: Moved (server-side)
2021/06/01 05:29:55 INFO  : movefile: Moved (server-side)
=== RUN   TestIntegration/FsMkdir/FsPutChunked
    fstests.go:1782: *chunker.Fs does not implement SetUploadChunkSizer
=== RUN   TestIntegration/FsMkdir/FsUploadUnknownSize
=== RUN   TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize
2021/06/01 05:30:03 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:30:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:30:04 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:30:04 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:30:04 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:30:10 INFO  : unknown-size-put.txt.rclone_chunk.001_0htvot: Moved (server-side) to: unknown-size-put.txt.rclone_chunk.001
2021/06/01 05:30:12 INFO  : unknown-size-put.txt.rclone_chunk.002_0htvot: Moved (server-side) to: unknown-size-put.txt.rclone_chunk.002
2021/06/01 05:30:13 INFO  : unknown-size-put.txt.rclone_chunk.003_0htvot: Moved (server-side) to: unknown-size-put.txt.rclone_chunk.003
=== RUN   TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize
2021/06/01 05:30:29 INFO  : unknown-size-update.txt.rclone_chunk.001_0huekw: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.001
2021/06/01 05:30:30 INFO  : unknown-size-update.txt.rclone_chunk.002_0huekw: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.002
2021/06/01 05:30:45 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2021/06/01 05:30:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:30:46 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:30:46 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:30:47 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:30:53 INFO  : unknown-size-update.txt.rclone_chunk.001_0huux8: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.001
2021/06/01 05:30:54 INFO  : unknown-size-update.txt.rclone_chunk.002_0huux8: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.002
2021/06/01 05:30:55 INFO  : unknown-size-update.txt.rclone_chunk.003_0huux8: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.003
2021/06/01 05:30:57 INFO  : unknown-size-update.txt.rclone_chunk.004_0huux8: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.004
2021/06/01 05:30:58 INFO  : unknown-size-update.txt.rclone_chunk.005_0huux8: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.005
=== RUN   TestIntegration/FsMkdir/FsRootCollapse
2021/06/01 05:31:08 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-ceziyal9rafopas2talupij8/deeper/nonexisting/directory"
2021/06/01 05:31:08 DEBUG : Creating backend with remote "TestYandex:rclone-test-ceziyal9rafopas2talupij8/deeper/nonexisting/directory"
2021/06/01 05:31:08 DEBUG : Reset feature "ListR"
2021/06/01 05:31:12 ERROR : : error listing: directory not found
=== CONT  TestIntegration/FsMkdir
    fstests.go:1964: Warning: this should produce fs.ErrorDirNotFound
=== RUN   TestIntegration/FsShutdown
    fstests.go:1978: Shutdown method not supported
--- FAIL: TestIntegration (1291.93s)
    --- PASS: TestIntegration/FsCheckWrap (0.00s)
    --- SKIP: TestIntegration/FsCommand (0.00s)
    --- PASS: TestIntegration/FsRmdirNotFound (0.19s)
    --- PASS: TestIntegration/FsString (0.00s)
    --- PASS: TestIntegration/FsName (0.00s)
    --- PASS: TestIntegration/FsRoot (0.00s)
    --- PASS: TestIntegration/FsRmdirEmpty (1.03s)
    --- FAIL: TestIntegration/FsMkdir (1289.47s)
        --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (5.44s)
        --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.24s)
        --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.19s)
        --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.20s)
        --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s)
        --- FAIL: TestIntegration/FsMkdir/FsEncoding (372.51s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (21.52s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (23.14s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (19.37s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (18.14s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (20.58s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (19.19s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (21.52s)
            --- FAIL: TestIntegration/FsMkdir/FsEncoding/leading_LF (19.29s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (20.25s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (19.45s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (26.33s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (18.28s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (19.48s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (22.30s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (19.40s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (23.47s)
            --- FAIL: TestIntegration/FsMkdir/FsEncoding/trailing_dot (17.89s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (22.66s)
        --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.38s)
        --- PASS: TestIntegration/FsMkdir/FsPutError (82.13s)
        --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (6.19s)
        --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s)
        --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsPutFiles (749.25s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (1.22s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (1.07s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.90s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (0.69s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.42s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.46s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (3.92s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.78s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (1.88s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.25s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (23.43s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (13.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (40.11s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (18.09s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.19s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.38s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.37s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.43s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.96s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.38s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.42s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (1.92s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.40s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (2.05s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (1.41s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (5.79s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (1.14s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (23.99s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.38s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (0.70s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (0.36s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (24.46s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (1.84s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.61s)
                --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (22.01s)
                    --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (7.32s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/PublicLink (0.00s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.40s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (7.44s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.13s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (27.38s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (5.53s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (21.86s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (487.76s)
                --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal/PutLarge (0.00s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkNameFormat (0.02s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/SmallFileInternals (13.68s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/PreventCorruption (192.71s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkNumberOverflow (21.93s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/MetadataInput (57.65s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/FutureProof (24.38s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/BackwardsCompatibility (149.47s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkerServerSideMove (27.92s)
        --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (67.20s)
            --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (19.33s)
            --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (47.87s)
        --- PASS: TestIntegration/FsMkdir/FsRootCollapse (0.39s)
    --- SKIP: TestIntegration/FsShutdown (0.00s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	1291.966s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024" - Finished ERROR in 21m36.775962924s (try 1/5): exit status 1: Failed [TestIntegration/FsMkdir/FsEncoding/leading_LF TestIntegration/FsMkdir/FsEncoding/trailing_dot]