"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestServerSideCopyOverSelf|TestSyncBackupDirWithSuffix|TestSyncConcurrentTruncate|TestSyncCopyDest)$'" - Starting (try 2/5)
2024/11/04 02:22:44 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-gacamim6siqo"
2024/11/04 02:22:44 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/04 02:22:44 DEBUG : Creating backend with remote "TestYandex:rclone-test-gacamim6siqo"
2024/11/04 02:22:45 DEBUG : Reset feature "ListR"
2024/11/04 02:22:45 DEBUG : Creating backend with remote "/tmp/rclone3464933205"
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo'", Local "Local file system at /tmp/rclone3464933205", Modify Window "1ns"
2024/11/04 02:22:55 INFO  : sub dir/hello world.rclone_chunk.001_es687r: Moved (server-side) to: sub dir/hello world
2024/11/04 02:22:56 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-cowirod3fazi"
2024/11/04 02:22:56 DEBUG : Creating backend with remote "TestYandex:rclone-test-cowirod3fazi"
2024/11/04 02:22:56 DEBUG : Reset feature "ListR"
    sync_test.go:626: Server side copy (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-cowirod3fazi'
2024/11/04 02:22:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/04 02:22:57 DEBUG : sub dir/hello world: copy non-chunked object...
2024/11/04 02:22:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cowirod3fazi': Waiting for checks to finish
2024/11/04 02:22:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cowirod3fazi': Waiting for transfers to finish
2024/11/04 02:22:59 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2024/11/04 02:22:59 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo'", Local "Local file system at /tmp/rclone3464933205", Modify Window "1ns"
2024/11/04 02:23:06 INFO  : sub dir/hello world: Deleted
2024/11/04 02:23:07 INFO  : sub dir/hello world.rclone_chunk.001_es6n7z: Moved (server-side) to: sub dir/hello world
2024/11/04 02:23:08 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/11/04 02:23:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cowirod3fazi': Waiting for checks to finish
2024/11/04 02:23:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cowirod3fazi': Waiting for transfers to finish
2024/11/04 02:23:08 DEBUG : sub dir/hello world: copy non-chunked object...
2024/11/04 02:23:09 ERROR : sub dir/hello world: Failed to copy: couldn't copy file: [409 - DiskResourceAlreadyExistsError] Resource "disk:/rclone-test-cowirod3fazi/sub dir/hello world" already exists. (Ресурс "disk:/rclone-test-cowirod3fazi/sub dir/hello world" уже существует.)
    sync_test.go:639: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:639
        	Error:      	Received unexpected error:
        	            	couldn't copy file: [409 - DiskResourceAlreadyExistsError] Resource "disk:/rclone-test-cowirod3fazi/sub dir/hello world" already exists. (Ресурс "disk:/rclone-test-cowirod3fazi/sub dir/hello world" уже существует.)
        	Test:       	TestServerSideCopyOverSelf
2024/11/04 02:23:09 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-cowirod3fazi': Purge remote
--- FAIL: TestServerSideCopyOverSelf (31.28s)
=== RUN   TestSyncCopyDest
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo'", Local "Local file system at /tmp/rclone3464933205", Modify Window "1ns"
2024/11/04 02:23:16 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst"
2024/11/04 02:23:16 DEBUG : Config file has changed externally - reloading
2024/11/04 02:23:16 DEBUG : Creating backend with remote "TestYandex:rclone-test-gacamim6siqo/dst"
2024/11/04 02:23:16 DEBUG : Reset feature "ListR"
2024/11/04 02:23:16 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/CopyDest"
2024/11/04 02:23:16 DEBUG : Creating backend with remote "TestYandex:rclone-test-gacamim6siqo/CopyDest"
2024/11/04 02:23:16 DEBUG : Reset feature "ListR"
2024/11/04 02:23:17 DEBUG : one: Need to transfer - File not found at Destination
2024/11/04 02:23:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for checks to finish
2024/11/04 02:23:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for transfers to finish
2024/11/04 02:23:17 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:23:22 INFO  : one.rclone_chunk.001_es73ta: Moved (server-side) to: one
2024/11/04 02:23:22 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2024/11/04 02:23:22 INFO  : one: Copied (new)
2024/11/04 02:23:22 DEBUG : Waiting for deletions to finish
2024/11/04 02:23:24 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/11/04 02:23:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for checks to finish
2024/11/04 02:23:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for transfers to finish
2024/11/04 02:23:24 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:23:28 INFO  : one: Deleted
2024/11/04 02:23:30 INFO  : one.rclone_chunk.001_es7ayi: Moved (server-side) to: one
2024/11/04 02:23:30 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2024/11/04 02:23:30 INFO  : one: Copied (replaced existing)
2024/11/04 02:23:30 DEBUG : Waiting for deletions to finish
2024/11/04 02:23:37 INFO  : dst/one: Deleted
2024/11/04 02:23:39 INFO  : dst/one.rclone_chunk.001_es7hz2: Moved (server-side) to: dst/one
2024/11/04 02:23:43 INFO  : CopyDest/one.rclone_chunk.001_es7p1y: Moved (server-side) to: CopyDest/one
    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:2281
        	Error:      	Should be true
        	Test:       	TestSyncCopyDest
        	Messages:   	dst/one: Modification time difference too big |-208198h18m31.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-11-04 02:23:38 +0000 UTC) (precision 1ns)
2024/11/04 02:23:44 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/BackupDir"
2024/11/04 02:23:44 DEBUG : Config file has changed externally - reloading
2024/11/04 02:23:44 DEBUG : Creating backend with remote "TestYandex:rclone-test-gacamim6siqo/BackupDir"
2024/11/04 02:23:44 DEBUG : Reset feature "ListR"
2024/11/04 02:23:45 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/11/04 02:23:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for checks to finish
2024/11/04 02:23:45 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:23:45 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/11/04 02:23:46 DEBUG : one: move non-chunked object...
2024/11/04 02:23:48 INFO  : one: Moved (server-side)
2024/11/04 02:23:48 INFO  : one: Moved (server-side)
2024/11/04 02:23:48 DEBUG : one: copy non-chunked object...
2024/11/04 02:23:50 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2024/11/04 02:23:50 INFO  : one: Copied (server-side copy)
2024/11/04 02:23:50 DEBUG : one: Destination found in --copy-dest, using server-side copy
2024/11/04 02:23:50 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for transfers to finish
2024/11/04 02:23:50 DEBUG : Waiting for deletions to finish
    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:2294
        	Error:      	Should be true
        	Test:       	TestSyncCopyDest
        	Messages:   	BackupDir/one: Modification time difference too big |-208198h18m40.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-11-04 02:23:47 +0000 UTC) (precision 1ns)
2024/11/04 02:23:55 INFO  : CopyDest/two.rclone_chunk.001_es81ey: Moved (server-side) to: CopyDest/two
    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:2300
        	Error:      	Should be true
        	Test:       	TestSyncCopyDest
        	Messages:   	BackupDir/one: Modification time difference too big |-208198h18m40.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-11-04 02:23:47 +0000 UTC) (precision 1ns)
2024/11/04 02:23:57 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:23:57 DEBUG : two: copy non-chunked object...
2024/11/04 02:23:59 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2024/11/04 02:23:59 INFO  : two: Copied (server-side copy)
2024/11/04 02:23:59 DEBUG : two: Destination found in --copy-dest, using server-side copy
2024/11/04 02:23:59 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:23:59 DEBUG : one: Unchanged skipping
2024/11/04 02:23:59 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for checks to finish
2024/11/04 02:23:59 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for transfers to finish
2024/11/04 02:23:59 DEBUG : Waiting for deletions to finish
    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:2312
        	Error:      	Should be true
        	Test:       	TestSyncCopyDest
        	Messages:   	BackupDir/one: Modification time difference too big |-208198h18m40.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-11-04 02:23:47 +0000 UTC) (precision 1ns)
2024/11/04 02:24:00 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:24:00 DEBUG : one: Unchanged skipping
2024/11/04 02:24:00 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:24:00 DEBUG : two: Unchanged skipping
2024/11/04 02:24:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for checks to finish
2024/11/04 02:24:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for transfers to finish
2024/11/04 02:24:00 DEBUG : Waiting for deletions to finish
2024/11/04 02:24:00 INFO  : There was nothing to transfer
    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:2321
        	Error:      	Should be true
        	Test:       	TestSyncCopyDest
        	Messages:   	BackupDir/one: Modification time difference too big |-208198h18m40.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-11-04 02:23:47 +0000 UTC) (precision 1ns)
2024/11/04 02:24:05 INFO  : CopyDest/three.rclone_chunk.001_es8buh: Moved (server-side) to: CopyDest/three
    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:2326
        	Error:      	Should be true
        	Test:       	TestSyncCopyDest
        	Messages:   	BackupDir/one: Modification time difference too big |-208198h18m40.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-11-04 02:23:47 +0000 UTC) (precision 1ns)
2024/11/04 02:24:07 DEBUG : three: Sizes differ (src 7 vs dst 5)
2024/11/04 02:24:07 DEBUG : three: Destination not found in --copy-dest
2024/11/04 02:24:07 DEBUG : three: Need to transfer - File not found at Destination
2024/11/04 02:24:07 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:24:07 DEBUG : one: Unchanged skipping
2024/11/04 02:24:07 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:24:07 DEBUG : two: Unchanged skipping
2024/11/04 02:24:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for checks to finish
2024/11/04 02:24:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for transfers to finish
2024/11/04 02:24:07 DEBUG : three: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:24:11 INFO  : three.rclone_chunk.001_es8hvb: Moved (server-side) to: three
2024/11/04 02:24:11 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK
2024/11/04 02:24:11 INFO  : three: Copied (new)
2024/11/04 02:24:11 DEBUG : Waiting for deletions to finish
    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:2338
        	Error:      	Should be true
        	Test:       	TestSyncCopyDest
        	Messages:   	BackupDir/one: Modification time difference too big |-208198h18m40.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-11-04 02:23:47 +0000 UTC) (precision 1ns)
--- FAIL: TestSyncCopyDest (73.03s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo'", Local "Local file system at /tmp/rclone3464933205", Modify Window "1ns"
2024/11/04 02:24:34 INFO  : dst/one.rclone_chunk.001_es93q1: Moved (server-side) to: dst/one
2024/11/04 02:24:39 INFO  : dst/two.rclone_chunk.001_es98kx: Moved (server-side) to: dst/two
2024/11/04 02:24:45 INFO  : dst/three.txt.rclone_chunk.001_es9ecw: Moved (server-side) to: dst/three.txt
2024/11/04 02:24:46 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst"
2024/11/04 02:24:46 DEBUG : Creating backend with remote "TestYandex:rclone-test-gacamim6siqo/dst"
2024/11/04 02:24:47 DEBUG : Reset feature "ListR"
2024/11/04 02:24:47 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/backup"
2024/11/04 02:24:47 DEBUG : Creating backend with remote "TestYandex:rclone-test-gacamim6siqo/backup"
2024/11/04 02:24:47 DEBUG : Reset feature "ListR"
2024/11/04 02:24:47 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/11/04 02:24:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:24:47 DEBUG : two: Unchanged skipping
2024/11/04 02:24:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for checks to finish
2024/11/04 02:24:47 DEBUG : one: move non-chunked object...
2024/11/04 02:24:49 INFO  : one: Moved (server-side) to: one.bak
2024/11/04 02:24:49 INFO  : one: Moved (server-side) to: one.bak
2024/11/04 02:24:49 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for transfers to finish
2024/11/04 02:24:50 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:24:54 INFO  : one.rclone_chunk.001_es9of1: Moved (server-side) to: one
2024/11/04 02:24:54 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2024/11/04 02:24:54 INFO  : one: Copied (new)
2024/11/04 02:24:54 DEBUG : Waiting for deletions to finish
2024/11/04 02:24:55 DEBUG : three.txt: move non-chunked object...
2024/11/04 02:24:56 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/11/04 02:24:56 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/11/04 02:24:56 INFO  : three.txt: Moved into backup dir
2024/11/04 02:25:03 INFO  : dst/three.txt.rclone_chunk.001_es9x8l: Moved (server-side) to: dst/three.txt
2024/11/04 02:25:05 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/11/04 02:25:05 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:25:05 DEBUG : two: Unchanged skipping
2024/11/04 02:25:05 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for checks to finish
2024/11/04 02:25:06 INFO  : one.bak: Deleted
2024/11/04 02:25:06 DEBUG : one: move non-chunked object...
2024/11/04 02:25:08 INFO  : one: Moved (server-side) to: one.bak
2024/11/04 02:25:08 INFO  : one: Moved (server-side) to: one.bak
2024/11/04 02:25:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo/dst': Waiting for transfers to finish
2024/11/04 02:25:08 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:25:35 INFO  : one.rclone_chunk.001_esa6al: Moved (server-side) to: one
2024/11/04 02:25:35 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2024/11/04 02:25:35 INFO  : one: Copied (new)
2024/11/04 02:25:35 DEBUG : Waiting for deletions to finish
2024/11/04 02:25:38 INFO  : three.txt.bak: Deleted
2024/11/04 02:25:38 DEBUG : three.txt: move non-chunked object...
2024/11/04 02:25:40 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/11/04 02:25:40 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/11/04 02:25:40 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffix (81.00s)
=== RUN   TestSyncConcurrentTruncate
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo'", Local "Local file system at /tmp/rclone3464933205", Modify Window "1ns"
2024/11/04 02:25:54 INFO  : both0.rclone_chunk.001_esbcxr: Moved (server-side) to: both0
2024/11/04 02:25:58 INFO  : only0.rclone_chunk.001_esbgqf: Moved (server-side) to: only0
2024/11/04 02:26:02 INFO  : both1.rclone_chunk.001_esbki7: Moved (server-side) to: both1
2024/11/04 02:26:06 INFO  : only1.rclone_chunk.001_esbof7: Moved (server-side) to: only1
2024/11/04 02:26:11 INFO  : both2.rclone_chunk.001_esbs9d: Moved (server-side) to: both2
2024/11/04 02:26:16 INFO  : only2.rclone_chunk.001_esbyl7: Moved (server-side) to: only2
2024/11/04 02:26:21 INFO  : both3.rclone_chunk.001_esc3i2: Moved (server-side) to: both3
2024/11/04 02:26:25 INFO  : only3.rclone_chunk.001_esc7nr: Moved (server-side) to: only3
2024/11/04 02:26:30 INFO  : both4.rclone_chunk.001_escbiv: Moved (server-side) to: both4
2024/11/04 02:26:34 INFO  : only4.rclone_chunk.001_escg14: Moved (server-side) to: only4
2024/11/04 02:26:40 INFO  : both5.rclone_chunk.001_escknj: Moved (server-side) to: both5
2024/11/04 02:26:45 INFO  : only5.rclone_chunk.001_escq6n: Moved (server-side) to: only5
2024/11/04 02:26:50 INFO  : both6.rclone_chunk.001_escwb1: Moved (server-side) to: both6
2024/11/04 02:26:54 INFO  : only6.rclone_chunk.001_esd0jw: Moved (server-side) to: only6
2024/11/04 02:27:00 INFO  : both7.rclone_chunk.001_esd4jk: Moved (server-side) to: both7
2024/11/04 02:27:04 INFO  : only7.rclone_chunk.001_esdawk: Moved (server-side) to: only7
2024/11/04 02:27:09 INFO  : both8.rclone_chunk.001_esdfsj: Moved (server-side) to: both8
2024/11/04 02:27:13 INFO  : only8.rclone_chunk.001_esdjjf: Moved (server-side) to: only8
2024/11/04 02:27:19 INFO  : both9.rclone_chunk.001_esdndl: Moved (server-side) to: both9
2024/11/04 02:27:23 INFO  : only9.rclone_chunk.001_esdten: Moved (server-side) to: only9
2024/11/04 02:27:28 INFO  : both10.rclone_chunk.001_esdyyr: Moved (server-side) to: both10
2024/11/04 02:27:34 INFO  : only10.rclone_chunk.001_ese3p6: Moved (server-side) to: only10
2024/11/04 02:27:39 INFO  : both11.rclone_chunk.001_ese8k4: Moved (server-side) to: both11
2024/11/04 02:27:44 INFO  : only11.rclone_chunk.001_esed5b: Moved (server-side) to: only11
2024/11/04 02:27:51 INFO  : both12.rclone_chunk.001_esejoc: Moved (server-side) to: both12
2024/11/04 02:27:56 INFO  : only12.rclone_chunk.001_esepeq: Moved (server-side) to: only12
2024/11/04 02:28:02 INFO  : both13.rclone_chunk.001_esevm3: Moved (server-side) to: both13
2024/11/04 02:28:09 INFO  : only13.rclone_chunk.001_esf055: Moved (server-side) to: only13
2024/11/04 02:28:14 INFO  : both14.rclone_chunk.001_esf7wp: Moved (server-side) to: both14
2024/11/04 02:28:19 INFO  : only14.rclone_chunk.001_esfcc4: Moved (server-side) to: only14
2024/11/04 02:28:24 INFO  : both15.rclone_chunk.001_esfhxh: Moved (server-side) to: both15
2024/11/04 02:28:30 INFO  : only15.rclone_chunk.001_esfmpx: Moved (server-side) to: only15
2024/11/04 02:28:34 INFO  : both16.rclone_chunk.001_esfssf: Moved (server-side) to: both16
2024/11/04 02:28:50 INFO  : only16.rclone_chunk.001_esfw9y: Moved (server-side) to: only16
2024/11/04 02:28:57 INFO  : both17.rclone_chunk.001_esgcse: Moved (server-side) to: both17
2024/11/04 02:29:02 INFO  : only17.rclone_chunk.001_esgkv9: Moved (server-side) to: only17
2024/11/04 02:29:07 INFO  : both18.rclone_chunk.001_esgpvo: Moved (server-side) to: both18
2024/11/04 02:29:14 INFO  : only18.rclone_chunk.001_esgu6d: Moved (server-side) to: only18
2024/11/04 02:29:19 INFO  : both19.rclone_chunk.001_esh15l: Moved (server-side) to: both19
2024/11/04 02:29:22 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2024/11/04 02:29:22 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/11/04 02:29:22 DEBUG : pacer: Reducing sleep to 15ms
2024/11/04 02:29:22 DEBUG : pacer: Reducing sleep to 11.25ms
2024/11/04 02:29:22 DEBUG : pacer: Reducing sleep to 10ms
2024/11/04 02:29:24 INFO  : only19.rclone_chunk.001_esh5o2: Moved (server-side) to: only19
2024/11/04 02:29:25 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo': Waiting for checks to finish
2024/11/04 02:29:25 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both0: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both12: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both13: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both14: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both15: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both1: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both17: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both18: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both19: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both2: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both10: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both3: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both5: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both6: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both7: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both8: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/04 02:29:25 DEBUG : both9: Unchanged skipping
2024/11/04 02:29:25 DEBUG : only0: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only1: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only10: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only11: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only12: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only13: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only14: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only15: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only16: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only17: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only18: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only19: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only2: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only3: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only4: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only5: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only6: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only7: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only8: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : only9: Sizes differ (src 0 vs dst 6)
2024/11/04 02:29:25 DEBUG : both11: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both16: Unchanged skipping
2024/11/04 02:29:25 DEBUG : both4: Unchanged skipping
2024/11/04 02:29:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo': Waiting for transfers to finish
2024/11/04 02:29:25 DEBUG : only0: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:25 DEBUG : only1: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:25 DEBUG : only10: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:25 DEBUG : only11: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:30 INFO  : only10: Deleted
2024/11/04 02:29:30 INFO  : only1: Deleted
2024/11/04 02:29:31 INFO  : only11: Deleted
2024/11/04 02:29:31 INFO  : only10.rclone_chunk.001_eshbxd: Moved (server-side) to: only10
2024/11/04 02:29:31 DEBUG : only10: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:31 INFO  : only10: Copied (replaced existing)
2024/11/04 02:29:31 DEBUG : only12: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:32 INFO  : only0: Deleted
2024/11/04 02:29:33 INFO  : only11.rclone_chunk.001_eshb3b: Moved (server-side) to: only11
2024/11/04 02:29:33 DEBUG : only11: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:33 INFO  : only11: Copied (replaced existing)
2024/11/04 02:29:33 DEBUG : only13: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:34 INFO  : only0.rclone_chunk.001_eshbas: Moved (server-side) to: only0
2024/11/04 02:29:34 DEBUG : only0: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:34 INFO  : only0: Copied (replaced existing)
2024/11/04 02:29:34 DEBUG : only14: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:36 INFO  : only1.rclone_chunk.001_eshbg9: Moved (server-side) to: only1
2024/11/04 02:29:36 DEBUG : only1: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:36 INFO  : only1: Copied (replaced existing)
2024/11/04 02:29:36 DEBUG : only15: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:36 INFO  : only12: Deleted
2024/11/04 02:29:37 INFO  : only13: Deleted
2024/11/04 02:29:38 INFO  : only14: Deleted
2024/11/04 02:29:38 INFO  : only12.rclone_chunk.001_eshhaa: Moved (server-side) to: only12
2024/11/04 02:29:38 DEBUG : only12: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:38 INFO  : only12: Copied (replaced existing)
2024/11/04 02:29:38 DEBUG : only16: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:39 INFO  : only14.rclone_chunk.001_eshkay: Moved (server-side) to: only14
2024/11/04 02:29:39 DEBUG : only14: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:39 INFO  : only14: Copied (replaced existing)
2024/11/04 02:29:39 DEBUG : only17: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:40 INFO  : only13.rclone_chunk.001_eshj56: Moved (server-side) to: only13
2024/11/04 02:29:40 DEBUG : only13: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:40 INFO  : only13: Copied (replaced existing)
2024/11/04 02:29:40 DEBUG : only18: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:41 INFO  : only15: Deleted
2024/11/04 02:29:43 INFO  : only15.rclone_chunk.001_eshmr0: Moved (server-side) to: only15
2024/11/04 02:29:43 DEBUG : only15: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:43 INFO  : only15: Copied (replaced existing)
2024/11/04 02:29:43 DEBUG : only19: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:43 INFO  : only17: Deleted
2024/11/04 02:29:44 INFO  : only18: Deleted
2024/11/04 02:29:44 INFO  : only17.rclone_chunk.001_eshp7n: Moved (server-side) to: only17
2024/11/04 02:29:44 DEBUG : only17: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:44 INFO  : only17: Copied (replaced existing)
2024/11/04 02:29:44 DEBUG : only2: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:46 INFO  : only18.rclone_chunk.001_eshqva: Moved (server-side) to: only18
2024/11/04 02:29:46 DEBUG : only18: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:46 INFO  : only18: Copied (replaced existing)
2024/11/04 02:29:46 DEBUG : only3: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:47 INFO  : only16: Deleted
2024/11/04 02:29:48 INFO  : only19: Deleted
2024/11/04 02:29:48 INFO  : only16.rclone_chunk.001_esho79: Moved (server-side) to: only16
2024/11/04 02:29:48 DEBUG : only16: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:48 INFO  : only16: Copied (replaced existing)
2024/11/04 02:29:48 DEBUG : only4: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:48 INFO  : only2: Deleted
2024/11/04 02:29:50 INFO  : only19.rclone_chunk.001_eshtcc: Moved (server-side) to: only19
2024/11/04 02:29:50 DEBUG : only19: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:50 INFO  : only19: Copied (replaced existing)
2024/11/04 02:29:50 DEBUG : only5: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:50 INFO  : only3: Deleted
2024/11/04 02:29:51 INFO  : only2.rclone_chunk.001_eshumb: Moved (server-side) to: only2
2024/11/04 02:29:51 DEBUG : only2: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:51 INFO  : only2: Copied (replaced existing)
2024/11/04 02:29:51 DEBUG : only6: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:52 INFO  : only3.rclone_chunk.001_eshwd6: Moved (server-side) to: only3
2024/11/04 02:29:52 DEBUG : only3: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:52 INFO  : only3: Copied (replaced existing)
2024/11/04 02:29:52 DEBUG : only7: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:52 INFO  : only4: Deleted
2024/11/04 02:29:54 INFO  : only4.rclone_chunk.001_eshyxh: Moved (server-side) to: only4
2024/11/04 02:29:54 DEBUG : only4: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:54 INFO  : only4: Copied (replaced existing)
2024/11/04 02:29:54 DEBUG : only8: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:56 INFO  : only6: Deleted
2024/11/04 02:29:56 INFO  : only7: Deleted
2024/11/04 02:29:57 INFO  : only5: Deleted
2024/11/04 02:29:57 INFO  : only6.rclone_chunk.001_esi1x9: Moved (server-side) to: only6
2024/11/04 02:29:57 DEBUG : only6: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:57 INFO  : only6: Copied (replaced existing)
2024/11/04 02:29:57 DEBUG : only9: skip slow MD5 on source file, hashing in-transit
2024/11/04 02:29:58 INFO  : only7.rclone_chunk.001_esi2nk: Moved (server-side) to: only7
2024/11/04 02:29:58 DEBUG : only7: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:58 INFO  : only7: Copied (replaced existing)
2024/11/04 02:29:59 INFO  : only5.rclone_chunk.001_esi054: Moved (server-side) to: only5
2024/11/04 02:29:59 DEBUG : only5: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:29:59 INFO  : only5: Copied (replaced existing)
2024/11/04 02:30:00 INFO  : only8: Deleted
2024/11/04 02:30:01 INFO  : only8.rclone_chunk.001_esi4k4: Moved (server-side) to: only8
2024/11/04 02:30:01 DEBUG : only8: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:30:01 INFO  : only8: Copied (replaced existing)
2024/11/04 02:30:02 INFO  : only9: Deleted
2024/11/04 02:30:04 INFO  : only9.rclone_chunk.001_esi7ub: Moved (server-side) to: only9
2024/11/04 02:30:04 DEBUG : only9: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/11/04 02:30:04 INFO  : only9: Copied (replaced existing)
2024/11/04 02:30:04 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo'", Local "Local file system at /tmp/rclone3464933205", Modify Window "1ns"
--- PASS: TestSyncConcurrentTruncate (311.07s)
FAIL
2024/11/04 02:31:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-gacamim6siqo': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestServerSideCopyOverSelf|TestSyncBackupDirWithSuffix|TestSyncConcurrentTruncate|TestSyncCopyDest)$'" - Finished ERROR in 8m18.629181701s (try 2/5): exit status 1: Failed [TestServerSideCopyOverSelf TestSyncCopyDest]