"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestCopyAfterDelete|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncSuffixKeepExtension|TestSyncWithTrackRenamesStrategyModtime|TestSyncWithUpdateOlder)$'" - Starting (try 2/5) 2025/01/26 03:25:55 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-boxesal7cuci" 2025/01/26 03:25:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/26 03:25:55 DEBUG : Creating backend with remote "TestYandex:rclone-test-boxesal7cuci" 2025/01/26 03:25:55 DEBUG : Reset feature "ListR" 2025/01/26 03:25:55 DEBUG : Creating backend with remote "/tmp/rclone1421544166" === RUN TestCopyAfterDelete run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci'", Local "Local file system at /tmp/rclone1421544166", Modify Window "1ns" 2025/01/26 03:26:00 INFO : sub dir/hello world.rclone_chunk.001_okga23: Moved (server-side) to: sub dir/hello world 2025/01/26 03:26:00 INFO : Local file system at /tmp/rclone1421544166: Making directory 2025/01/26 03:26:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for checks to finish 2025/01/26 03:26:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for transfers to finish 2025/01/26 03:26:01 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci'", Local "Local file system at /tmp/rclone1421544166", Modify Window "1ns" --- PASS: TestCopyAfterDelete (15.61s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci'", Local "Local file system at /tmp/rclone1421544166", Modify Window "1ns" 2025/01/26 03:26:15 INFO : potato.rclone_chunk.001_okgpme: Moved (server-side) to: potato 2025/01/26 03:26:20 INFO : empty space.rclone_chunk.001_okguv9: Moved (server-side) to: empty space 2025/01/26 03:26:21 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/26 03:26:21 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/01/26 03:26:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for checks to finish 2025/01/26 03:26:21 DEBUG : empty space: Unchanged skipping 2025/01/26 03:26:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for transfers to finish 2025/01/26 03:26:21 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/01/26 03:26:30 INFO : potato2.rclone_chunk.001_okgza8: Moved (server-side) to: potato2.rclone_chunk.001 2025/01/26 03:26:31 INFO : potato2.rclone_chunk.002_okgza8: Moved (server-side) to: potato2.rclone_chunk.002 2025/01/26 03:26:34 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/01/26 03:26:34 INFO : potato2: Copied (new) 2025/01/26 03:26:34 DEBUG : Waiting for deletions to finish 2025/01/26 03:26:38 INFO : potato: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci'", Local "Local file system at /tmp/rclone1421544166", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (44.15s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci'", Local "Local file system at /tmp/rclone1421544166", Modify Window "1ns" 2025/01/26 03:27:00 INFO : one.rclone_chunk.001_okhxca: Moved (server-side) to: one 2025/01/26 03:27:04 INFO : two.rclone_chunk.001_oki22m: Moved (server-side) to: two 2025/01/26 03:27:10 INFO : three.rclone_chunk.001_oki651: Moved (server-side) to: three 2025/01/26 03:27:14 INFO : four.rclone_chunk.001_okicz8: Moved (server-side) to: four 2025/01/26 03:27:15 DEBUG : five: Need to transfer - File not found at Destination 2025/01/26 03:27:15 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/01/26 03:27:15 DEBUG : one: Destination is newer than source, skipping 2025/01/26 03:27:15 DEBUG : three: Sizes identical 2025/01/26 03:27:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for checks to finish 2025/01/26 03:27:15 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/01/26 03:27:15 DEBUG : four: skip slow MD5 on source file, hashing in-transit 2025/01/26 03:27:15 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 (Local file system at /tmp/rclone1421544166) 2025/01/26 03:27:15 DEBUG : two: md5 = 0f82d86afa0f5dc965c5c15aca58dcfb (Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci') 2025/01/26 03:27:15 DEBUG : two: md5 differ 2025/01/26 03:27:15 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/01/26 03:27:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for transfers to finish 2025/01/26 03:27:15 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2025/01/26 03:27:15 DEBUG : five: skip slow MD5 on source file, hashing in-transit 2025/01/26 03:27:19 INFO : five.rclone_chunk.001_okihu2: Moved (server-side) to: five 2025/01/26 03:27:19 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2025/01/26 03:27:19 INFO : five: Copied (new) 2025/01/26 03:27:21 INFO : four: Deleted 2025/01/26 03:27:21 INFO : two: Deleted 2025/01/26 03:27:22 INFO : four.rclone_chunk.001_okihum: Moved (server-side) to: four 2025/01/26 03:27:22 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2025/01/26 03:27:22 INFO : four: Copied (replaced existing) 2025/01/26 03:27:23 INFO : two.rclone_chunk.001_okih5k: Moved (server-side) to: two 2025/01/26 03:27:23 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/01/26 03:27:23 INFO : two: Copied (replaced existing) 2025/01/26 03:27:23 DEBUG : Waiting for deletions to finish 2025/01/26 03:27:23 DEBUG : one: Destination is newer than source, skipping 2025/01/26 03:27:23 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for checks to finish 2025/01/26 03:27:23 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2025/01/26 03:27:23 DEBUG : five: Size and md5 of src and dst objects identical 2025/01/26 03:27:23 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 (Local file system at /tmp/rclone1421544166) 2025/01/26 03:27:23 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2025/01/26 03:27:23 DEBUG : three: md5 = 413af0de1f97a2155acf2b8b26ab36e2 (Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci') 2025/01/26 03:27:23 DEBUG : three: md5 differ 2025/01/26 03:27:23 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/01/26 03:27:23 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2025/01/26 03:27:23 DEBUG : two: Size and md5 of src and dst objects identical 2025/01/26 03:27:23 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2025/01/26 03:27:23 DEBUG : four: Size and md5 of src and dst objects identical 2025/01/26 03:27:23 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2025/01/26 03:27:23 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2025/01/26 03:27:23 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2025/01/26 03:27:23 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for transfers to finish 2025/01/26 03:27:30 INFO : three: Deleted 2025/01/26 03:27:31 INFO : three.rclone_chunk.001_okipo0: Moved (server-side) to: three 2025/01/26 03:27:31 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2025/01/26 03:27:31 INFO : three: Copied (replaced existing) 2025/01/26 03:27:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (51.81s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci'", Local "Local file system at /tmp/rclone1421544166", Modify Window "1ns" sync_test.go:1610: Can track renames: true 2025/01/26 03:27:47 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Making map for --track-renames 2025/01/26 03:27:47 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Finished making map for --track-renames 2025/01/26 03:27:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for checks to finish 2025/01/26 03:27:47 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/01/26 03:27:47 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/01/26 03:27:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for renames to finish 2025/01/26 03:27:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for transfers to finish 2025/01/26 03:27:47 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2025/01/26 03:27:47 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/01/26 03:27:50 INFO : yam.rclone_chunk.001_okjdou: Moved (server-side) to: yam 2025/01/26 03:27:50 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2025/01/26 03:27:50 INFO : yam: Copied (new) 2025/01/26 03:27:52 INFO : potato.rclone_chunk.001_okjdaq: Moved (server-side) to: potato 2025/01/26 03:27:52 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2025/01/26 03:27:52 INFO : potato: Copied (new) 2025/01/26 03:27:52 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci'", Local "Local file system at /tmp/rclone1421544166", Modify Window "1ns" 2025/01/26 03:27:53 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/01/26 03:27:53 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Making map for --track-renames 2025/01/26 03:27:53 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Finished making map for --track-renames 2025/01/26 03:27:53 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for checks to finish 2025/01/26 03:27:53 DEBUG : potato: Unchanged skipping 2025/01/26 03:27:53 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for renames to finish 2025/01/26 03:27:53 DEBUG : yam: move non-chunked object... 2025/01/26 03:27:55 INFO : yam: Moved (server-side) to: yaml 2025/01/26 03:27:55 INFO : yam: Moved (server-side) to: yaml 2025/01/26 03:27:55 INFO : yaml: Renamed from "yam" 2025/01/26 03:27:55 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Waiting for transfers to finish 2025/01/26 03:27:55 DEBUG : Waiting for deletions to finish 2025/01/26 03:27:55 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci'", Local "Local file system at /tmp/rclone1421544166", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyModtime (19.67s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci'", Local "Local file system at /tmp/rclone1421544166", Modify Window "1ns" 2025/01/26 03:28:10 INFO : dst/one.rclone_chunk.001_okjxi9: Moved (server-side) to: dst/one 2025/01/26 03:28:17 INFO : dst/two.rclone_chunk.001_okk17q: Moved (server-side) to: dst/two 2025/01/26 03:28:21 INFO : dst/three.txt.rclone_chunk.001_okk7o9: Moved (server-side) to: dst/three.txt 2025/01/26 03:28:22 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-boxesal7cuci/dst" 2025/01/26 03:28:22 DEBUG : Creating backend with remote "TestYandex:rclone-test-boxesal7cuci/dst" 2025/01/26 03:28:22 DEBUG : Reset feature "ListR" 2025/01/26 03:28:23 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/26 03:28:24 DEBUG : one: move non-chunked object... 2025/01/26 03:28:25 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/26 03:28:25 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/26 03:28:25 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/01/26 03:28:30 INFO : one.rclone_chunk.001_okkflk: Moved (server-side) to: one 2025/01/26 03:28:30 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/01/26 03:28:30 INFO : one: Copied (new) 2025/01/26 03:28:30 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/01/26 03:28:30 DEBUG : two: Unchanged skipping 2025/01/26 03:28:31 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/01/26 03:28:32 DEBUG : three.txt: move non-chunked object... 2025/01/26 03:28:33 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/26 03:28:33 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/26 03:28:34 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2025/01/26 03:28:38 INFO : three.txt.rclone_chunk.001_okkovk: Moved (server-side) to: three.txt 2025/01/26 03:28:38 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/01/26 03:28:38 INFO : three.txt: Copied (new) 2025/01/26 03:28:40 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/26 03:28:44 INFO : one-2019-01-01: Deleted 2025/01/26 03:28:44 DEBUG : one: move non-chunked object... 2025/01/26 03:28:45 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/26 03:28:45 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/26 03:28:46 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/01/26 03:28:50 INFO : one.rclone_chunk.001_okl054: Moved (server-side) to: one 2025/01/26 03:28:50 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/01/26 03:28:50 INFO : one: Copied (new) 2025/01/26 03:28:51 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/01/26 03:28:51 DEBUG : two: Unchanged skipping 2025/01/26 03:28:51 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/01/26 03:28:55 INFO : three-2019-01-01.txt: Deleted 2025/01/26 03:28:55 DEBUG : three.txt: move non-chunked object... 2025/01/26 03:28:56 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/26 03:28:56 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/26 03:28:57 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2025/01/26 03:29:01 INFO : three.txt.rclone_chunk.001_oklbgj: Moved (server-side) to: three.txt 2025/01/26 03:29:01 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/01/26 03:29:01 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (77.07s) PASS 2025/01/26 03:29:23 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-boxesal7cuci': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024 -test.run '^(TestCopyAfterDelete|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncSuffixKeepExtension|TestSyncWithTrackRenamesStrategyModtime|TestSyncWithUpdateOlder)$'" - Finished OK in 3m31.513390749s (try 2/5)