"./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckDownload|TestCheckSum|TestCheckSumDownload|TestCount|TestListDirSorted|TestMoveFileBackupDir|TestRcatSize)$|^TestRcat$/^withChecksum=true,ignoreChecksum=true$'" - Starting (try 2/5)
2025/04/27 02:02:32 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-sibokey5feya"
2025/04/27 02:02:32 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/04/27 02:02:32 DEBUG : Creating backend with remote "TestYandex:rclone-test-sibokey5feya"
2025/04/27 02:02:33 DEBUG : Creating backend with remote "/tmp/rclone2426082416"
=== RUN   TestCheckDownload
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-sibokey5feya'", Local "Local file system at /tmp/rclone2426082416", Modify Window "1ns"
2025/04/27 02:02:38 INFO  : rutabaga.rclone_chunk.001_czabb7: Moved (server-side) to: rutabaga
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2025/04/27 02:02:45 INFO  : empty space.rclone_chunk.001_czah9v: Moved (server-side) to: empty space
=== RUN   TestCheckDownload/3
2025/04/27 02:02:53 INFO  : potato2.rclone_chunk.001_czaote: Moved (server-side) to: potato2.rclone_chunk.001
2025/04/27 02:02:55 INFO  : potato2.rclone_chunk.002_czaote: Moved (server-side) to: potato2.rclone_chunk.002
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2025/04/27 02:03:08 INFO  : remotepotato.rclone_chunk.001_czb3n4: Moved (server-side) to: remotepotato.rclone_chunk.001
2025/04/27 02:03:09 INFO  : remotepotato.rclone_chunk.002_czb3n4: Moved (server-side) to: remotepotato.rclone_chunk.002
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (76.35s)
    --- PASS: TestCheckDownload/1 (1.35s)
    --- PASS: TestCheckDownload/2 (0.73s)
    --- PASS: TestCheckDownload/3 (0.79s)
    --- PASS: TestCheckDownload/4 (2.15s)
    --- PASS: TestCheckDownload/5 (1.62s)
    --- PASS: TestCheckDownload/6 (1.49s)
    --- PASS: TestCheckDownload/7 (1.60s)
=== RUN   TestCheckSum
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-sibokey5feya'", Local "Local file system at /tmp/rclone2426082416", Modify Window "1ns"
2025/04/27 02:03:49 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-sibokey5feya/data"
2025/04/27 02:03:49 DEBUG : Config file has changed externally - reloading
2025/04/27 02:03:49 DEBUG : Creating backend with remote "TestYandex:rclone-test-sibokey5feya/data"
2025/04/27 02:03:55 INFO  : data/banana.rclone_chunk.001_czcfr2: Moved (server-side) to: data/banana
2025/04/27 02:04:00 INFO  : test.sum.rclone_chunk.001_czckva: Moved (server-side) to: test.sum
=== RUN   TestCheckSum/subtest1
2025/04/27 02:04:07 INFO  : data/potato.rclone_chunk.001_czcrsx: Moved (server-side) to: data/potato
2025/04/27 02:04:15 INFO  : test.sum: Deleted
2025/04/27 02:04:16 INFO  : test.sum.rclone_chunk.001_czcw4v: Moved (server-side) to: test.sum
=== RUN   TestCheckSum/subtest2
2025/04/27 02:04:26 INFO  : test.sum.rclone_chunk.001_czd8vj: Moved (server-side) to: test.sum.rclone_chunk.001
2025/04/27 02:04:30 INFO  : test.sum.rclone_chunk.002_czd8vj: Moved (server-side) to: test.sum.rclone_chunk.002
=== RUN   TestCheckSum/subtest3
2025/04/27 02:04:52 INFO  : test.sum.rclone_chunk.001_czdq85: Moved (server-side) to: test.sum.rclone_chunk.001
2025/04/27 02:04:54 INFO  : test.sum.rclone_chunk.002_czdq85: Moved (server-side) to: test.sum.rclone_chunk.002
=== RUN   TestCheckSum/subtest4
2025/04/27 02:05:21 INFO  : test.sum.rclone_chunk.001_czefqk: Moved (server-side) to: test.sum.rclone_chunk.001
2025/04/27 02:05:22 INFO  : test.sum.rclone_chunk.002_czefqk: Moved (server-side) to: test.sum.rclone_chunk.002
2025/04/27 02:05:24 INFO  : test.sum.rclone_chunk.003_czefqk: Moved (server-side) to: test.sum.rclone_chunk.003
=== RUN   TestCheckSum/subtest5
2025/04/27 02:05:52 INFO  : test.sum.rclone_chunk.001_czf9y7: Moved (server-side) to: test.sum.rclone_chunk.001
2025/04/27 02:05:54 INFO  : test.sum.rclone_chunk.002_czf9y7: Moved (server-side) to: test.sum.rclone_chunk.002
2025/04/27 02:05:56 INFO  : test.sum.rclone_chunk.003_czf9y7: Moved (server-side) to: test.sum.rclone_chunk.003
=== RUN   TestCheckSum/subtest6
2025/04/27 02:06:12 INFO  : data/banana: Deleted
2025/04/27 02:06:13 INFO  : data/banana.rclone_chunk.001_czg5nm: Moved (server-side) to: data/banana
2025/04/27 02:06:21 INFO  : data/potato: Deleted
2025/04/27 02:06:23 INFO  : data/potato.rclone_chunk.001_czgfwc: Moved (server-side) to: data/potato
2025/04/27 02:06:48 INFO  : test.sum.rclone_chunk.001_czgp2f: Moved (server-side) to: test.sum.rclone_chunk.001
2025/04/27 02:06:50 INFO  : test.sum.rclone_chunk.002_czgp2f: Moved (server-side) to: test.sum.rclone_chunk.002
=== RUN   TestCheckSum/subtest7
--- PASS: TestCheckSum (218.36s)
    --- PASS: TestCheckSum/subtest1 (1.46s)
    --- PASS: TestCheckSum/subtest2 (1.61s)
    --- PASS: TestCheckSum/subtest3 (3.10s)
    --- PASS: TestCheckSum/subtest4 (3.25s)
    --- PASS: TestCheckSum/subtest5 (2.87s)
    --- PASS: TestCheckSum/subtest6 (3.50s)
    --- PASS: TestCheckSum/subtest7 (3.00s)
=== RUN   TestCheckSumDownload
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-sibokey5feya'", Local "Local file system at /tmp/rclone2426082416", Modify Window "1ns"
2025/04/27 02:07:27 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-sibokey5feya/data"
2025/04/27 02:07:27 DEBUG : Creating backend with remote "TestYandex:rclone-test-sibokey5feya/data"
2025/04/27 02:07:34 INFO  : data/banana.rclone_chunk.001_czihma: Moved (server-side) to: data/banana
2025/04/27 02:07:39 INFO  : test.sum.rclone_chunk.001_czinix: Moved (server-side) to: test.sum
=== RUN   TestCheckSumDownload/subtest1
2025/04/27 02:07:49 INFO  : data/potato.rclone_chunk.001_cziwpk: Moved (server-side) to: data/potato
2025/04/27 02:07:58 INFO  : test.sum: Deleted
2025/04/27 02:07:59 INFO  : test.sum.rclone_chunk.001_czj3oe: Moved (server-side) to: test.sum
=== RUN   TestCheckSumDownload/subtest2
2025/04/27 02:08:10 INFO  : test.sum.rclone_chunk.001_czjfxj: Moved (server-side) to: test.sum.rclone_chunk.001
2025/04/27 02:08:12 INFO  : test.sum.rclone_chunk.002_czjfxj: Moved (server-side) to: test.sum.rclone_chunk.002
=== RUN   TestCheckSumDownload/subtest3
2025/04/27 02:08:38 INFO  : test.sum.rclone_chunk.001_czjzq9: Moved (server-side) to: test.sum.rclone_chunk.001
2025/04/27 02:08:39 INFO  : test.sum.rclone_chunk.002_czjzq9: Moved (server-side) to: test.sum.rclone_chunk.002
=== RUN   TestCheckSumDownload/subtest4
2025/04/27 02:09:06 INFO  : test.sum.rclone_chunk.001_czkow5: Moved (server-side) to: test.sum.rclone_chunk.001
2025/04/27 02:09:07 INFO  : test.sum.rclone_chunk.002_czkow5: Moved (server-side) to: test.sum.rclone_chunk.002
2025/04/27 02:09:09 INFO  : test.sum.rclone_chunk.003_czkow5: Moved (server-side) to: test.sum.rclone_chunk.003
=== RUN   TestCheckSumDownload/subtest5
2025/04/27 02:09:43 INFO  : test.sum.rclone_chunk.001_czljas: Moved (server-side) to: test.sum.rclone_chunk.001
2025/04/27 02:09:44 INFO  : test.sum.rclone_chunk.002_czljas: Moved (server-side) to: test.sum.rclone_chunk.002
2025/04/27 02:09:46 INFO  : test.sum.rclone_chunk.003_czljas: Moved (server-side) to: test.sum.rclone_chunk.003
=== RUN   TestCheckSumDownload/subtest6
2025/04/27 02:10:05 INFO  : data/banana: Deleted
2025/04/27 02:10:06 INFO  : data/banana.rclone_chunk.001_czmjjz: Moved (server-side) to: data/banana
2025/04/27 02:10:14 INFO  : data/potato: Deleted
2025/04/27 02:10:16 INFO  : data/potato.rclone_chunk.001_czmwez: Moved (server-side) to: data/potato
2025/04/27 02:10:36 INFO  : test.sum.rclone_chunk.001_czn67q: Moved (server-side) to: test.sum.rclone_chunk.001
2025/04/27 02:10:37 INFO  : test.sum.rclone_chunk.002_czn67q: Moved (server-side) to: test.sum.rclone_chunk.002
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (225.18s)
    --- PASS: TestCheckSumDownload/subtest1 (2.23s)
    --- PASS: TestCheckSumDownload/subtest2 (1.44s)
    --- PASS: TestCheckSumDownload/subtest3 (3.41s)
    --- PASS: TestCheckSumDownload/subtest4 (3.04s)
    --- PASS: TestCheckSumDownload/subtest5 (4.65s)
    --- PASS: TestCheckSumDownload/subtest6 (3.80s)
    --- PASS: TestCheckSumDownload/subtest7 (3.05s)
=== RUN   TestListDirSorted
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-sibokey5feya'", Local "Local file system at /tmp/rclone2426082416", Modify Window "1ns"
2025/04/27 02:11:18 INFO  : a.txt.rclone_chunk.001_czoqbm: Moved (server-side) to: a.txt
2025/04/27 02:11:23 INFO  : zend.txt.rclone_chunk.001_czovyg: Moved (server-side) to: zend.txt
2025/04/27 02:11:28 INFO  : sub dir/hello world.rclone_chunk.001_czp03v: Moved (server-side) to: sub dir/hello world
2025/04/27 02:11:34 INFO  : sub dir/hello world2.rclone_chunk.001_czp5am: Moved (server-side) to: sub dir/hello world2
2025/04/27 02:11:39 INFO  : sub dir/ignore dir/.ignore.rclone_chunk.001_czpb37: Moved (server-side) to: sub dir/ignore dir/.ignore
2025/04/27 02:11:44 INFO  : sub dir/ignore dir/should be ignored.rclone_chunk.001_czpg07: Moved (server-side) to: sub dir/ignore dir/should be ignored
2025/04/27 02:11:49 INFO  : sub dir/sub sub dir/hello world3.rclone_chunk.001_czplie: Moved (server-side) to: sub dir/sub sub dir/hello world3
2025/04/27 02:11:51 DEBUG : a.txt: Excluded (Size Filter)
2025/04/27 02:11:51 DEBUG : a.txt: Excluded
2025/04/27 02:11:52 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/04/27 02:11:52 DEBUG : sub dir/hello world: Excluded
2025/04/27 02:11:52 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/04/27 02:11:52 DEBUG : sub dir/hello world2: Excluded
2025/04/27 02:11:52 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/04/27 02:11:52 DEBUG : sub dir/hello world: Excluded
2025/04/27 02:11:52 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/04/27 02:11:52 DEBUG : sub dir/hello world2: Excluded
2025/04/27 02:11:53 DEBUG : sub dir/ignore dir: Excluded
2025/04/27 02:11:53 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSorted (85.55s)
=== RUN   TestCount
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-sibokey5feya'", Local "Local file system at /tmp/rclone2426082416", Modify Window "1ns"
2025/04/27 02:12:45 INFO  : potato2.rclone_chunk.001_czr3tb: Moved (server-side) to: potato2.rclone_chunk.001
2025/04/27 02:12:46 INFO  : potato2.rclone_chunk.002_czr3tb: Moved (server-side) to: potato2.rclone_chunk.002
2025/04/27 02:12:56 INFO  : empty space.rclone_chunk.001_czrf3c: Moved (server-side) to: empty space
2025/04/27 02:13:01 INFO  : sub dir/potato3.rclone_chunk.001_czrl85: Moved (server-side) to: sub dir/potato3
--- PASS: TestCount (54.60s)
=== RUN   TestMoveFileBackupDir
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-sibokey5feya'", Local "Local file system at /tmp/rclone2426082416", Modify Window "1ns"
2025/04/27 02:13:37 INFO  : dst/file1.rclone_chunk.001_czsmh8: Moved (server-side) to: dst/file1
2025/04/27 02:13:38 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-sibokey5feya/backup"
2025/04/27 02:13:38 DEBUG : Config file has changed externally - reloading
2025/04/27 02:13:38 DEBUG : Creating backend with remote "TestYandex:rclone-test-sibokey5feya/backup"
2025/04/27 02:13:39 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18)
2025/04/27 02:13:39 DEBUG : dst/file1: move non-chunked object...
2025/04/27 02:13:42 INFO  : dst/file1: Moved (server-side)
2025/04/27 02:13:42 INFO  : dst/file1: Moved (server-side)
2025/04/27 02:13:43 DEBUG : dst/file1: skip slow MD5 on source file, hashing in-transit
2025/04/27 02:13:48 INFO  : dst/file1.rclone_chunk.001_czswqu: Moved (server-side) to: dst/file1
2025/04/27 02:13:48 DEBUG : dst/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2025/04/27 02:13:48 INFO  : dst/file1: Copied (new)
2025/04/27 02:13:48 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (47.78s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=true
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-sibokey5feya'", Local "Local file system at /tmp/rclone2426082416", Modify Window "1ns"
    operations_test.go:1495: Adjust StreamingUploadCutoff to size limit 1Ki (was 100Ki)
2025/04/27 02:14:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-sibokey5feya': File to upload is small (34 bytes), uploading instead of streaming
2025/04/27 02:14:26 INFO  : ignore_checksum_small_file_from_pipe.rclone_chunk.001_cztyh0: Moved (server-side) to: ignore_checksum_small_file_from_pipe
2025/04/27 02:14:26 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check
2025/04/27 02:14:26 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2025/04/27 02:15:18 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.001_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.001
2025/04/27 02:15:20 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.002_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.002
2025/04/27 02:15:22 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.003_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.003
2025/04/27 02:15:24 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.004_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.004
2025/04/27 02:15:27 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.005_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.005
2025/04/27 02:15:29 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.006_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.006
2025/04/27 02:15:30 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.007_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.007
2025/04/27 02:15:32 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.008_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.008
2025/04/27 02:15:33 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.009_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.009
2025/04/27 02:15:35 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.010_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.010
2025/04/27 02:15:36 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.011_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.011
2025/04/27 02:15:38 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.012_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.012
2025/04/27 02:15:40 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.013_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.013
2025/04/27 02:15:42 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.014_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.014
2025/04/27 02:15:43 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.015_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.015
2025/04/27 02:15:45 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.016_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.016
2025/04/27 02:15:46 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.017_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.017
2025/04/27 02:15:48 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.018_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.018
2025/04/27 02:15:50 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.019_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.019
2025/04/27 02:15:52 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.020_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.020
2025/04/27 02:15:54 INFO  : ignore_checksum_big_file_from_pipe.rclone_chunk.021_czu3fh: Moved (server-side) to: ignore_checksum_big_file_from_pipe.rclone_chunk.021
2025/04/27 02:15:56 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check
2025/04/27 02:15:56 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (193.97s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (193.97s)
=== RUN   TestRcatSize
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-sibokey5feya'", Local "Local file system at /tmp/rclone2426082416", Modify Window "1ns"
2025/04/27 02:17:41 INFO  : potato1.rclone_chunk.001_czzc1s: Moved (server-side) to: potato1.rclone_chunk.001
2025/04/27 02:17:43 INFO  : potato1.rclone_chunk.002_czzc1s: Moved (server-side) to: potato1.rclone_chunk.002
2025/04/27 02:17:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-sibokey5feya': File to upload is small (60 bytes), uploading instead of streaming
2025/04/27 02:17:52 INFO  : potato2.rclone_chunk.001_czznhl: Moved (server-side) to: potato2.rclone_chunk.001
2025/04/27 02:17:53 INFO  : potato2.rclone_chunk.002_czznhl: Moved (server-side) to: potato2.rclone_chunk.002
2025/04/27 02:17:56 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/04/27 02:17:56 DEBUG : potato2: Size and md5 of src and dst objects identical
--- PASS: TestRcatSize (49.26s)
PASS
2025/04/27 02:18:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-sibokey5feya': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckDownload|TestCheckSum|TestCheckSumDownload|TestCount|TestListDirSorted|TestMoveFileBackupDir|TestRcatSize)$|^TestRcat$/^withChecksum=true,ignoreChecksum=true$'" - Finished OK in 15m56.381448572s (try 2/5)