"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestFixCase|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCompareDest|TestSyncConcurrentDelete|TestSyncDeleteBefore|TestSyncIgnoreCase|TestSyncImmutable|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm)$|^TestMaxTransfer$/^(Cautious|Hard|Soft)$'" - Starting (try 2/5)
2025/05/15 02:08:08 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bacogad8gele"
2025/05/15 02:08:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/15 02:08:08 DEBUG : Creating backend with remote "TestYandex:rclone-test-bacogad8gele"
2025/05/15 02:08:09 DEBUG : Creating backend with remote "/tmp/rclone782216070"
=== RUN   TestSyncDeleteBefore
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
2025/05/15 02:08:16 INFO  : potato.rclone_chunk.001_abjum1: Moved (server-side) to: potato
2025/05/15 02:08:21 INFO  : empty space.rclone_chunk.001_abk0x6: Moved (server-side) to: empty space
2025/05/15 02:08:21 DEBUG : Waiting for deletions to finish
2025/05/15 02:08:22 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for checks to finish
2025/05/15 02:08:22 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for transfers to finish
2025/05/15 02:08:27 INFO  : potato: Deleted
2025/05/15 02:08:27 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:08:27 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/15 02:08:27 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for checks to finish
2025/05/15 02:08:27 DEBUG : empty space: Unchanged skipping
2025/05/15 02:08:27 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for transfers to finish
2025/05/15 02:08:27 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:08:33 INFO  : potato2.rclone_chunk.001_abkb2e: Moved (server-side) to: potato2.rclone_chunk.001
2025/05/15 02:08:35 INFO  : potato2.rclone_chunk.002_abkb2e: Moved (server-side) to: potato2.rclone_chunk.002
2025/05/15 02:08:37 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/05/15 02:08:37 INFO  : potato2: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
--- PASS: TestSyncDeleteBefore (49.86s)
=== RUN   TestSyncCompareDest
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
2025/05/15 02:08:59 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst"
2025/05/15 02:08:59 DEBUG : Config file has changed externally - reloading
2025/05/15 02:08:59 DEBUG : Creating backend with remote "TestYandex:rclone-test-bacogad8gele/dst"
2025/05/15 02:08:59 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bacogad8gele/CompareDest"
2025/05/15 02:08:59 DEBUG : Creating backend with remote "TestYandex:rclone-test-bacogad8gele/CompareDest"
2025/05/15 02:09:00 DEBUG : one: Need to transfer - File not found at Destination
2025/05/15 02:09:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:09:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:09:00 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:09:08 INFO  : one.rclone_chunk.001_abl8i4: Moved (server-side) to: one
2025/05/15 02:09:08 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2025/05/15 02:09:08 INFO  : one: Copied (new)
2025/05/15 02:09:08 DEBUG : Waiting for deletions to finish
2025/05/15 02:09:10 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/15 02:09:10 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:09:10 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:09:10 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:09:18 INFO  : one: Deleted
2025/05/15 02:09:19 INFO  : one.rclone_chunk.001_ablib9: Moved (server-side) to: one
2025/05/15 02:09:19 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2025/05/15 02:09:19 INFO  : one: Copied (replaced existing)
2025/05/15 02:09:19 DEBUG : Waiting for deletions to finish
2025/05/15 02:09:29 INFO  : dst/one: Deleted
2025/05/15 02:09:31 INFO  : dst/one.rclone_chunk.001_abltfc: Moved (server-side) to: dst/one
2025/05/15 02:09:35 INFO  : CompareDest/one.rclone_chunk.001_abm3iw: Moved (server-side) to: CompareDest/one
2025/05/15 02:09:37 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/15 02:09:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:09:37 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:09:37 DEBUG : one: Destination found in --compare-dest, skipping
2025/05/15 02:09:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:09:37 DEBUG : Waiting for deletions to finish
2025/05/15 02:09:37 INFO  : There was nothing to transfer
2025/05/15 02:09:43 INFO  : CompareDest/two.rclone_chunk.001_abmaxo: Moved (server-side) to: CompareDest/two
2025/05/15 02:09:45 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/15 02:09:46 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:09:46 DEBUG : two: Destination found in --compare-dest, skipping
2025/05/15 02:09:46 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:09:46 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:09:46 DEBUG : one: Destination found in --compare-dest, skipping
2025/05/15 02:09:46 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:09:46 DEBUG : Waiting for deletions to finish
2025/05/15 02:09:46 INFO  : There was nothing to transfer
2025/05/15 02:09:47 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/15 02:09:48 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:09:48 DEBUG : one: Destination found in --compare-dest, skipping
2025/05/15 02:09:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:09:48 DEBUG : two: Destination found in --compare-dest, skipping
2025/05/15 02:09:48 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:09:48 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:09:48 DEBUG : Waiting for deletions to finish
2025/05/15 02:09:48 INFO  : There was nothing to transfer
2025/05/15 02:09:49 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/15 02:09:50 DEBUG : two: Modification times differ by -119h59m59.876543211s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59.123456789 +0000 UTC
2025/05/15 02:09:50 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2025/05/15 02:09:50 DEBUG : two: Destination found in --compare-dest, skipping
2025/05/15 02:09:50 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:09:50 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:09:50 DEBUG : one: Destination found in --compare-dest, skipping
2025/05/15 02:09:50 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:09:50 DEBUG : Waiting for deletions to finish
2025/05/15 02:09:50 INFO  : There was nothing to transfer
2025/05/15 02:09:51 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/15 02:09:52 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:09:52 DEBUG : one: Destination found in --compare-dest, skipping
2025/05/15 02:09:52 DEBUG : two: Sizes differ (src 5 vs dst 3)
2025/05/15 02:09:52 DEBUG : two: Need to transfer - File not found at Destination
2025/05/15 02:09:52 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:09:52 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:09:53 DEBUG : two: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:09:57 INFO  : two.rclone_chunk.001_abmppk: Moved (server-side) to: two
2025/05/15 02:09:57 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK
2025/05/15 02:09:57 INFO  : two: Copied (new)
2025/05/15 02:09:57 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (91.57s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
2025/05/15 02:10:35 INFO  : dst/one.rclone_chunk.001_abnq2y: Moved (server-side) to: dst/one
2025/05/15 02:10:37 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2025/05/15 02:10:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/05/15 02:10:38 DEBUG : pacer: Reducing sleep to 15ms
2025/05/15 02:10:38 DEBUG : pacer: Reducing sleep to 11.25ms
2025/05/15 02:10:38 DEBUG : pacer: Reducing sleep to 10ms
2025/05/15 02:10:40 INFO  : dst/two.rclone_chunk.001_abnv05: Moved (server-side) to: dst/two
2025/05/15 02:10:45 INFO  : dst/three.txt.rclone_chunk.001_abo1nn: Moved (server-side) to: dst/three.txt
    fstest.go:121: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2377
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431
        	Error:      	Should be true
        	Test:       	TestSyncBackupDirWithSuffix
        	Messages:   	dst/one: Modification time difference too big |-212806h5m27.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-15 02:10:34 +0000 UTC) (precision 1ns)
    fstest.go:121: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2377
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431
        	Error:      	Should be true
        	Test:       	TestSyncBackupDirWithSuffix
        	Messages:   	dst/three.txt: Modification time difference too big |-212806h5m38.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-15 02:10:45 +0000 UTC) (precision 1ns)
2025/05/15 02:10:46 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst"
2025/05/15 02:10:46 DEBUG : Config file has changed externally - reloading
2025/05/15 02:10:46 DEBUG : Creating backend with remote "TestYandex:rclone-test-bacogad8gele/dst"
2025/05/15 02:10:47 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bacogad8gele/backup"
2025/05/15 02:10:47 DEBUG : Creating backend with remote "TestYandex:rclone-test-bacogad8gele/backup"
2025/05/15 02:10:47 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/15 02:10:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:10:47 DEBUG : two: Unchanged skipping
2025/05/15 02:10:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:10:47 DEBUG : one: move non-chunked object...
2025/05/15 02:10:49 INFO  : one: Moved (server-side) to: one.bak
2025/05/15 02:10:49 INFO  : one: Moved (server-side) to: one.bak
2025/05/15 02:10:49 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:10:49 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:10:53 INFO  : one.rclone_chunk.001_abo9o6: Moved (server-side) to: one
2025/05/15 02:10:53 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/15 02:10:53 INFO  : one: Copied (new)
2025/05/15 02:10:53 DEBUG : Waiting for deletions to finish
2025/05/15 02:10:53 DEBUG : three.txt: move non-chunked object...
2025/05/15 02:10:55 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/15 02:10:55 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/15 02:10:55 INFO  : three.txt: Moved into backup dir
    fstest.go:121: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2398
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431
        	Error:      	Should be true
        	Test:       	TestSyncBackupDirWithSuffix
        	Messages:   	backup/one.bak: Modification time difference too big |-212806h5m41.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-15 02:10:48 +0000 UTC) (precision 1ns)
    fstest.go:121: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2398
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431
        	Error:      	Should be true
        	Test:       	TestSyncBackupDirWithSuffix
        	Messages:   	backup/three.txt.bak: Modification time difference too big |-212806h5m47.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-15 02:10:54 +0000 UTC) (precision 1ns)
2025/05/15 02:11:00 INFO  : dst/three.txt.rclone_chunk.001_abog8q: Moved (server-side) to: dst/three.txt
    fstest.go:121: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2404
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431
        	Error:      	Should be true
        	Test:       	TestSyncBackupDirWithSuffix
        	Messages:   	backup/one.bak: Modification time difference too big |-212806h5m41.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-15 02:10:48 +0000 UTC) (precision 1ns)
    fstest.go:121: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2404
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431
        	Error:      	Should be true
        	Test:       	TestSyncBackupDirWithSuffix
        	Messages:   	backup/three.txt.bak: Modification time difference too big |-212806h5m47.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-15 02:10:54 +0000 UTC) (precision 1ns)
2025/05/15 02:11:01 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/15 02:11:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:11:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:11:01 DEBUG : two: Unchanged skipping
2025/05/15 02:11:07 INFO  : one.bak: Deleted
2025/05/15 02:11:07 DEBUG : one: move non-chunked object...
2025/05/15 02:11:09 INFO  : one: Moved (server-side) to: one.bak
2025/05/15 02:11:09 INFO  : one: Moved (server-side) to: one.bak
2025/05/15 02:11:09 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:11:09 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:11:13 INFO  : one.rclone_chunk.001_abot3f: Moved (server-side) to: one
2025/05/15 02:11:13 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/15 02:11:13 INFO  : one: Copied (new)
2025/05/15 02:11:13 DEBUG : Waiting for deletions to finish
2025/05/15 02:11:19 INFO  : three.txt.bak: Deleted
2025/05/15 02:11:19 DEBUG : three.txt: move non-chunked object...
2025/05/15 02:11:20 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/15 02:11:20 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/15 02:11:20 INFO  : three.txt: Moved into backup dir
--- FAIL: TestSyncBackupDirWithSuffix (82.50s)
=== RUN   TestSyncBackupDirWithSuffixKeepExtension
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
2025/05/15 02:11:57 INFO  : dst/one.rclone_chunk.001_abq1pf: Moved (server-side) to: dst/one
2025/05/15 02:12:02 INFO  : dst/two.rclone_chunk.001_abq6bx: Moved (server-side) to: dst/two
2025/05/15 02:12:09 INFO  : dst/three.txt.rclone_chunk.001_abqavh: Moved (server-side) to: dst/three.txt
2025/05/15 02:12:10 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst"
2025/05/15 02:12:10 DEBUG : Creating backend with remote "TestYandex:rclone-test-bacogad8gele/dst"
2025/05/15 02:12:10 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bacogad8gele/backup"
2025/05/15 02:12:10 DEBUG : Creating backend with remote "TestYandex:rclone-test-bacogad8gele/backup"
2025/05/15 02:12:11 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/15 02:12:11 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:12:11 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:12:11 DEBUG : two: Unchanged skipping
2025/05/15 02:12:12 DEBUG : one: move non-chunked object...
2025/05/15 02:12:13 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/15 02:12:13 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/15 02:12:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:12:13 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:12:19 INFO  : one.rclone_chunk.001_abqli6: Moved (server-side) to: one
2025/05/15 02:12:19 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/15 02:12:19 INFO  : one: Copied (new)
2025/05/15 02:12:19 DEBUG : Waiting for deletions to finish
2025/05/15 02:12:19 DEBUG : three.txt: move non-chunked object...
2025/05/15 02:12:21 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/15 02:12:21 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/15 02:12:21 INFO  : three.txt: Moved into backup dir
2025/05/15 02:12:26 INFO  : dst/three.txt.rclone_chunk.001_abqun3: Moved (server-side) to: dst/three.txt
2025/05/15 02:12:27 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/15 02:12:27 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:12:27 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:12:27 DEBUG : two: Unchanged skipping
2025/05/15 02:12:32 INFO  : one-2019-01-01: Deleted
2025/05/15 02:12:32 DEBUG : one: move non-chunked object...
2025/05/15 02:12:34 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/15 02:12:34 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/15 02:12:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:12:34 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:12:38 INFO  : one.rclone_chunk.001_abr63i: Moved (server-side) to: one
2025/05/15 02:12:38 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/15 02:12:38 INFO  : one: Copied (new)
2025/05/15 02:12:38 DEBUG : Waiting for deletions to finish
2025/05/15 02:12:45 INFO  : three-2019-01-01.txt: Deleted
2025/05/15 02:12:45 DEBUG : three.txt: move non-chunked object...
2025/05/15 02:12:46 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/15 02:12:46 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/15 02:12:46 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffixKeepExtension (85.02s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
2025/05/15 02:13:22 INFO  : dst/one.rclone_chunk.001_absemc: Moved (server-side) to: dst/one
2025/05/15 02:13:27 INFO  : dst/two.rclone_chunk.001_absjzd: Moved (server-side) to: dst/two
2025/05/15 02:13:32 INFO  : dst/three.txt.rclone_chunk.001_absnx5: Moved (server-side) to: dst/three.txt
2025/05/15 02:13:33 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst"
2025/05/15 02:13:33 DEBUG : Config file has changed externally - reloading
2025/05/15 02:13:33 DEBUG : Creating backend with remote "TestYandex:rclone-test-bacogad8gele/dst"
2025/05/15 02:13:34 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/15 02:13:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:13:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:13:34 DEBUG : two: Unchanged skipping
2025/05/15 02:13:34 DEBUG : one: move non-chunked object...
2025/05/15 02:13:36 INFO  : one: Moved (server-side) to: one.bak
2025/05/15 02:13:36 INFO  : one: Moved (server-side) to: one.bak
2025/05/15 02:13:36 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:13:36 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:13:41 INFO  : one.rclone_chunk.001_abswjn: Moved (server-side) to: one
2025/05/15 02:13:41 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/15 02:13:41 INFO  : one: Copied (new)
2025/05/15 02:13:41 DEBUG : Waiting for deletions to finish
2025/05/15 02:13:41 DEBUG : three.txt: move non-chunked object...
2025/05/15 02:13:43 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/15 02:13:43 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/15 02:13:43 INFO  : three.txt: Moved into backup dir
2025/05/15 02:13:48 INFO  : dst/three.txt.rclone_chunk.001_abt45e: Moved (server-side) to: dst/three.txt
2025/05/15 02:13:49 DEBUG : one.bak: Excluded (Path Filter)
2025/05/15 02:13:49 DEBUG : one.bak: Excluded
2025/05/15 02:13:49 DEBUG : three.txt.bak: Excluded (Path Filter)
2025/05/15 02:13:49 DEBUG : three.txt.bak: Excluded
2025/05/15 02:13:49 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/15 02:13:49 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:13:49 DEBUG : two: Unchanged skipping
2025/05/15 02:13:49 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for checks to finish
2025/05/15 02:13:55 INFO  : one.bak: Deleted
2025/05/15 02:13:55 DEBUG : one: move non-chunked object...
2025/05/15 02:13:57 INFO  : one: Moved (server-side) to: one.bak
2025/05/15 02:13:57 INFO  : one: Moved (server-side) to: one.bak
2025/05/15 02:13:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst': Waiting for transfers to finish
2025/05/15 02:13:57 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:14:01 INFO  : one.rclone_chunk.001_abth6h: Moved (server-side) to: one
2025/05/15 02:14:01 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/15 02:14:01 INFO  : one: Copied (new)
2025/05/15 02:14:01 DEBUG : Waiting for deletions to finish
2025/05/15 02:14:07 INFO  : three.txt.bak: Deleted
2025/05/15 02:14:07 DEBUG : three.txt: move non-chunked object...
2025/05/15 02:14:09 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/15 02:14:09 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/15 02:14:09 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (76.16s)
=== RUN   TestSyncSuffix
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
2025/05/15 02:14:39 INFO  : dst/one.rclone_chunk.001_abuidh: Moved (server-side) to: dst/one
2025/05/15 02:14:43 INFO  : dst/two.rclone_chunk.001_abunvc: Moved (server-side) to: dst/two
2025/05/15 02:14:48 INFO  : dst/three.txt.rclone_chunk.001_aburk2: Moved (server-side) to: dst/three.txt
2025/05/15 02:14:49 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst"
2025/05/15 02:14:49 DEBUG : Config file has changed externally - reloading
2025/05/15 02:14:49 DEBUG : Creating backend with remote "TestYandex:rclone-test-bacogad8gele/dst"
2025/05/15 02:14:50 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/15 02:14:50 DEBUG : one: move non-chunked object...
2025/05/15 02:14:51 INFO  : one: Moved (server-side) to: one.bak
2025/05/15 02:14:51 INFO  : one: Moved (server-side) to: one.bak
2025/05/15 02:14:52 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:14:56 INFO  : one.rclone_chunk.001_abv0ax: Moved (server-side) to: one
2025/05/15 02:14:56 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/15 02:14:56 INFO  : one: Copied (new)
2025/05/15 02:14:56 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:14:56 DEBUG : two: Unchanged skipping
2025/05/15 02:14:57 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2025/05/15 02:14:57 DEBUG : three.txt: move non-chunked object...
2025/05/15 02:14:59 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/15 02:14:59 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/15 02:14:59 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:15:05 INFO  : three.txt.rclone_chunk.001_abv7k4: Moved (server-side) to: three.txt
2025/05/15 02:15:05 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2025/05/15 02:15:05 INFO  : three.txt: Copied (new)
2025/05/15 02:15:07 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/15 02:15:12 INFO  : one.bak: Deleted
2025/05/15 02:15:12 DEBUG : one: move non-chunked object...
2025/05/15 02:15:13 INFO  : one: Moved (server-side) to: one.bak
2025/05/15 02:15:13 INFO  : one: Moved (server-side) to: one.bak
2025/05/15 02:15:13 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:15:18 INFO  : one.rclone_chunk.001_abvl6j: Moved (server-side) to: one
2025/05/15 02:15:18 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/15 02:15:18 INFO  : one: Copied (new)
2025/05/15 02:15:19 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:15:19 DEBUG : two: Unchanged skipping
2025/05/15 02:15:20 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2025/05/15 02:15:25 INFO  : three.txt.bak: Deleted
2025/05/15 02:15:25 DEBUG : three.txt: move non-chunked object...
2025/05/15 02:15:26 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/15 02:15:26 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/15 02:15:26 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:15:30 INFO  : three.txt.rclone_chunk.001_abvy72: Moved (server-side) to: three.txt
2025/05/15 02:15:30 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2025/05/15 02:15:30 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffix (85.48s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
2025/05/15 02:16:04 INFO  : dst/one.rclone_chunk.001_abwwj8: Moved (server-side) to: dst/one
2025/05/15 02:16:10 INFO  : dst/two.rclone_chunk.001_abx1vq: Moved (server-side) to: dst/two
2025/05/15 02:16:14 INFO  : dst/three.txt.rclone_chunk.001_abx6iq: Moved (server-side) to: dst/three.txt
2025/05/15 02:16:15 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-bacogad8gele/dst"
2025/05/15 02:16:15 DEBUG : Config file has changed externally - reloading
2025/05/15 02:16:15 DEBUG : Creating backend with remote "TestYandex:rclone-test-bacogad8gele/dst"
2025/05/15 02:16:16 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/15 02:16:16 DEBUG : one: move non-chunked object...
2025/05/15 02:16:18 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/15 02:16:18 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/15 02:16:18 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:16:22 INFO  : one.rclone_chunk.001_abxeha: Moved (server-side) to: one
2025/05/15 02:16:22 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/15 02:16:22 INFO  : one: Copied (new)
2025/05/15 02:16:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:16:22 DEBUG : two: Unchanged skipping
2025/05/15 02:16:23 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2025/05/15 02:16:23 DEBUG : three.txt: move non-chunked object...
2025/05/15 02:16:25 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/15 02:16:25 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/15 02:16:25 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:16:30 INFO  : three.txt.rclone_chunk.001_abxlwq: Moved (server-side) to: three.txt
2025/05/15 02:16:30 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2025/05/15 02:16:30 INFO  : three.txt: Copied (new)
2025/05/15 02:16:32 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/15 02:16:37 INFO  : one-2019-01-01: Deleted
2025/05/15 02:16:37 DEBUG : one: move non-chunked object...
2025/05/15 02:16:38 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/15 02:16:38 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/15 02:16:39 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:16:43 INFO  : one.rclone_chunk.001_abxz9b: Moved (server-side) to: one
2025/05/15 02:16:43 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/15 02:16:43 INFO  : one: Copied (new)
2025/05/15 02:16:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:16:44 DEBUG : two: Unchanged skipping
2025/05/15 02:16:44 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2025/05/15 02:16:52 INFO  : three-2019-01-01.txt: Deleted
2025/05/15 02:16:52 DEBUG : three.txt: move non-chunked object...
2025/05/15 02:16:53 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/15 02:16:53 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/15 02:16:54 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:16:58 INFO  : three.txt.rclone_chunk.001_abyepv: Moved (server-side) to: three.txt
2025/05/15 02:16:58 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2025/05/15 02:16:58 INFO  : three.txt: Copied (new)
    fstest.go:121: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2521
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524
        	Error:      	Should be true
        	Test:       	TestSyncSuffixKeepExtension
        	Messages:   	dst/three.txt: Modification time difference too big |-117229h16m59s| > 1ns (want 2011-12-30 12:59:59 +0000 UTC vs got 2025-05-15 02:16:58 +0000 UTC) (precision 1ns)
--- FAIL: TestSyncSuffixKeepExtension (86.55s)
=== RUN   TestSyncUTFNorm
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
2025/05/15 02:17:32 INFO  : Testêé.rclone_chunk.001_abza2t: Moved (server-side) to: Testêé
2025/05/15 02:17:32 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for checks to finish
2025/05/15 02:17:32 DEBUG : Testêé: Sizes differ (src 14 vs dst 18)
2025/05/15 02:17:32 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for transfers to finish
2025/05/15 02:17:40 INFO  : Testêé: Deleted
2025/05/15 02:17:42 INFO  : Testêé.rclone_chunk.001_abzgsu: Moved (server-side) to: Testêé
2025/05/15 02:17:42 DEBUG : Testêé: md5 = ce114e4501d2f4e2dcea3e17b546f339 OK
2025/05/15 02:17:42 INFO  : Testêé: Copied (replaced existing) to: Testêé
2025/05/15 02:17:42 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncUTFNorm (22.35s)
=== RUN   TestSyncImmutable
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
2025/05/15 02:17:49 DEBUG : existing: Need to transfer - File not found at Destination
2025/05/15 02:17:49 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for checks to finish
2025/05/15 02:17:49 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for transfers to finish
2025/05/15 02:17:49 DEBUG : existing: skip slow MD5 on source file, hashing in-transit
2025/05/15 02:17:53 INFO  : existing.rclone_chunk.001_abzxel: Moved (server-side) to: existing
2025/05/15 02:17:53 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK
2025/05/15 02:17:53 INFO  : existing: Copied (new)
2025/05/15 02:17:53 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
2025/05/15 02:17:54 DEBUG : existing: Sizes differ (src 8 vs dst 6)
2025/05/15 02:17:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for checks to finish
2025/05/15 02:17:54 ERROR : existing: Source and destination exist but do not match: immutable file modified
2025/05/15 02:17:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for transfers to finish
2025/05/15 02:17:54 ERROR : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': not deleting files as there were IO errors
2025/05/15 02:17:54 ERROR : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': not deleting directories as there were IO errors
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
--- PASS: TestSyncImmutable (14.39s)
=== RUN   TestSyncIgnoreCase
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
2025/05/15 02:18:07 INFO  : EXISTING.rclone_chunk.001_ac0b03: Moved (server-side) to: EXISTING
2025/05/15 02:18:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for checks to finish
2025/05/15 02:18:08 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:18:08 DEBUG : existing: Unchanged skipping
2025/05/15 02:18:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for transfers to finish
2025/05/15 02:18:08 DEBUG : Waiting for deletions to finish
2025/05/15 02:18:08 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreCase (10.77s)
=== RUN   TestFixCase
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
    sync_test.go:2635: Skipping test as local or remote are case-sensitive
--- SKIP: TestFixCase (0.70s)
=== RUN   TestMaxTransfer
=== RUN   TestMaxTransfer/Hard
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
    sync_test.go:2674: This test only runs on local
=== RUN   TestMaxTransfer/Soft
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
    sync_test.go:2674: This test only runs on local
=== RUN   TestMaxTransfer/Cautious
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
    sync_test.go:2674: This test only runs on local
--- PASS: TestMaxTransfer (3.51s)
    --- SKIP: TestMaxTransfer/Hard (2.21s)
    --- SKIP: TestMaxTransfer/Soft (0.77s)
    --- SKIP: TestMaxTransfer/Cautious (0.53s)
=== RUN   TestSyncConcurrentDelete
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
2025/05/15 02:18:23 INFO  : both0.rclone_chunk.001_ac0qs9: Moved (server-side) to: both0
2025/05/15 02:18:27 INFO  : only0.rclone_chunk.001_ac0vnh: Moved (server-side) to: only0
2025/05/15 02:18:32 INFO  : both1.rclone_chunk.001_ac10k3: Moved (server-side) to: both1
2025/05/15 02:18:38 INFO  : only1.rclone_chunk.001_ac150v: Moved (server-side) to: only1
2025/05/15 02:18:43 INFO  : both2.rclone_chunk.001_ac1ajk: Moved (server-side) to: both2
2025/05/15 02:18:48 INFO  : only2.rclone_chunk.001_ac1f73: Moved (server-side) to: only2
2025/05/15 02:18:52 INFO  : both3.rclone_chunk.001_ac1kk2: Moved (server-side) to: both3
2025/05/15 02:18:59 INFO  : only3.rclone_chunk.001_ac1pea: Moved (server-side) to: only3
2025/05/15 02:19:04 INFO  : both4.rclone_chunk.001_ac1wkz: Moved (server-side) to: both4
2025/05/15 02:19:08 INFO  : only4.rclone_chunk.001_ac20zo: Moved (server-side) to: only4
2025/05/15 02:19:13 INFO  : both5.rclone_chunk.001_ac25sh: Moved (server-side) to: both5
2025/05/15 02:19:18 INFO  : only5.rclone_chunk.001_ac29yd: Moved (server-side) to: only5
2025/05/15 02:19:23 INFO  : both6.rclone_chunk.001_ac2e9e: Moved (server-side) to: both6
2025/05/15 02:19:28 INFO  : only6.rclone_chunk.001_ac2jfg: Moved (server-side) to: only6
2025/05/15 02:19:33 INFO  : both7.rclone_chunk.001_ac2okg: Moved (server-side) to: both7
2025/05/15 02:19:38 INFO  : only7.rclone_chunk.001_ac2tke: Moved (server-side) to: only7
2025/05/15 02:19:43 INFO  : both8.rclone_chunk.001_ac2y3m: Moved (server-side) to: both8
2025/05/15 02:19:48 INFO  : only8.rclone_chunk.001_ac33zk: Moved (server-side) to: only8
2025/05/15 02:19:53 INFO  : both9.rclone_chunk.001_ac389c: Moved (server-side) to: both9
2025/05/15 02:19:58 INFO  : only9.rclone_chunk.001_ac3ex9: Moved (server-side) to: only9
2025/05/15 02:20:20 INFO  : both10.rclone_chunk.001_ac3ivi: Moved (server-side) to: both10
2025/05/15 02:20:25 INFO  : only10.rclone_chunk.001_ac45hc: Moved (server-side) to: only10
2025/05/15 02:20:30 INFO  : both11.rclone_chunk.001_ac4adb: Moved (server-side) to: both11
2025/05/15 02:20:35 INFO  : only11.rclone_chunk.001_ac4e78: Moved (server-side) to: only11
2025/05/15 02:20:42 INFO  : both12.rclone_chunk.001_ac4jux: Moved (server-side) to: both12
2025/05/15 02:20:48 INFO  : only12.rclone_chunk.001_ac4rqs: Moved (server-side) to: only12
2025/05/15 02:20:52 INFO  : both13.rclone_chunk.001_ac4wq0: Moved (server-side) to: both13
2025/05/15 02:20:57 INFO  : only13.rclone_chunk.001_ac50u5: Moved (server-side) to: only13
2025/05/15 02:21:01 INFO  : both14.rclone_chunk.001_ac551h: Moved (server-side) to: both14
2025/05/15 02:21:06 INFO  : only14.rclone_chunk.001_ac5adp: Moved (server-side) to: only14
2025/05/15 02:21:11 INFO  : both15.rclone_chunk.001_ac5ftz: Moved (server-side) to: both15
2025/05/15 02:21:17 INFO  : only15.rclone_chunk.001_ac5jm6: Moved (server-side) to: only15
2025/05/15 02:21:21 INFO  : both16.rclone_chunk.001_ac5pid: Moved (server-side) to: both16
2025/05/15 02:21:25 INFO  : only16.rclone_chunk.001_ac5t1y: Moved (server-side) to: only16
2025/05/15 02:21:30 INFO  : both17.rclone_chunk.001_ac5y9u: Moved (server-side) to: both17
2025/05/15 02:21:36 INFO  : only17.rclone_chunk.001_ac622y: Moved (server-side) to: only17
2025/05/15 02:21:40 INFO  : both18.rclone_chunk.001_ac68le: Moved (server-side) to: both18
2025/05/15 02:21:45 INFO  : only18.rclone_chunk.001_ac6d8a: Moved (server-side) to: only18
2025/05/15 02:21:48 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2025/05/15 02:21:48 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/05/15 02:21:48 DEBUG : pacer: Reducing sleep to 15ms
2025/05/15 02:21:49 DEBUG : pacer: Reducing sleep to 11.25ms
2025/05/15 02:21:49 DEBUG : pacer: Reducing sleep to 10ms
2025/05/15 02:21:51 INFO  : both19.rclone_chunk.001_ac6hkt: Moved (server-side) to: both19
2025/05/15 02:21:56 INFO  : only19.rclone_chunk.001_ac6not: Moved (server-side) to: only19
    fstest.go:121: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2744
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2758
        	Error:      	Should be true
        	Test:       	TestSyncConcurrentDelete
        	Messages:   	only15: Modification time difference too big |-212806h16m9.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-15 02:21:16 +0000 UTC) (precision 1ns)
2025/05/15 02:21:57 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for checks to finish
2025/05/15 02:21:57 DEBUG : both0: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both12: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both13: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both14: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both10: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both16: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both17: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both18: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both19: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both11: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both1: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both15: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both5: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both6: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both7: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both2: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/15 02:21:57 DEBUG : both9: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both3: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both4: Unchanged skipping
2025/05/15 02:21:57 DEBUG : both8: Unchanged skipping
2025/05/15 02:21:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Waiting for transfers to finish
2025/05/15 02:21:57 DEBUG : Waiting for deletions to finish
2025/05/15 02:22:01 INFO  : only8: Deleted
2025/05/15 02:22:01 INFO  : only19: Deleted
2025/05/15 02:22:01 INFO  : only14: Deleted
2025/05/15 02:22:02 INFO  : only1: Deleted
2025/05/15 02:22:06 INFO  : only4: Deleted
2025/05/15 02:22:06 INFO  : only9: Deleted
2025/05/15 02:22:06 INFO  : only5: Deleted
2025/05/15 02:22:06 INFO  : only0: Deleted
2025/05/15 02:22:10 INFO  : only18: Deleted
2025/05/15 02:22:10 INFO  : only12: Deleted
2025/05/15 02:22:10 INFO  : only2: Deleted
2025/05/15 02:22:11 INFO  : only3: Deleted
2025/05/15 02:22:14 INFO  : only15: Deleted
2025/05/15 02:22:14 INFO  : only13: Deleted
2025/05/15 02:22:14 INFO  : only11: Deleted
2025/05/15 02:22:16 INFO  : only6: Deleted
2025/05/15 02:22:19 INFO  : only17: Deleted
2025/05/15 02:22:19 INFO  : only16: Deleted
2025/05/15 02:22:19 INFO  : only10: Deleted
2025/05/15 02:22:20 INFO  : only7: Deleted
2025/05/15 02:22:20 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele'", Local "Local file system at /tmp/rclone782216070", Modify Window "1ns"
--- FAIL: TestSyncConcurrentDelete (343.34s)
FAIL
2025/05/15 02:24:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-bacogad8gele': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestFixCase|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCompareDest|TestSyncConcurrentDelete|TestSyncDeleteBefore|TestSyncIgnoreCase|TestSyncImmutable|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm)$|^TestMaxTransfer$/^(Cautious|Hard|Soft)$'" - Finished ERROR in 15m57.312485826s (try 2/5): exit status 1: Failed [TestSyncBackupDirWithSuffix TestSyncSuffixKeepExtension TestSyncConcurrentDelete]