"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -fast-list -size-limit 1024 -test.run '^TestServerSideMove$'" - Starting (try 2/5)
2026/03/01 03:18:40 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-sogixuv9gala"
2026/03/01 03:18:40 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/01 03:18:40 DEBUG : Creating backend with remote "TestS3:rclone-test-sogixuv9gala"
2026/03/01 03:18:40 DEBUG : Creating backend with remote "/tmp/rclone679284249"
=== RUN   TestServerSideMove
    run.go:185: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-sogixuv9gala'", Local "Local file system at /tmp/rclone679284249", Modify Window "1ns"
2026/03/01 03:18:40 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-xuxadeh8koko"
2026/03/01 03:18:40 DEBUG : Creating backend with remote "TestS3:rclone-test-xuxadeh8koko"
2026/03/01 03:18:41 INFO  : S3 bucket rclone-test-sogixuv9gala: Bucket "rclone-test-sogixuv9gala" created with ACL ""
2026/03/01 03:18:41 DEBUG : potato2.rclone_chunk.001: size = 50 OK
2026/03/01 03:18:41 DEBUG : potato2.rclone_chunk.001_7g8azx: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2026/03/01 03:18:41 INFO  : potato2.rclone_chunk.001_7g8azx: Copied (server-side copy) to: potato2.rclone_chunk.001
2026/03/01 03:18:41 INFO  : potato2.rclone_chunk.001_7g8azx: Deleted
2026/03/01 03:18:41 DEBUG : potato2.rclone_chunk.002: size = 10 OK
2026/03/01 03:18:41 DEBUG : potato2.rclone_chunk.002_7g8azx: md5 = 01a6651a94ab602dbfc47587d234b52c OK
2026/03/01 03:18:41 INFO  : potato2.rclone_chunk.002_7g8azx: Copied (server-side copy) to: potato2.rclone_chunk.002
2026/03/01 03:18:41 INFO  : potato2.rclone_chunk.002_7g8azx: Deleted
2026/03/01 03:18:42 DEBUG : empty space: size = 1 OK
2026/03/01 03:18:42 DEBUG : empty space.rclone_chunk.001_7g8aoi: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2026/03/01 03:18:42 INFO  : empty space.rclone_chunk.001_7g8aoi: Copied (server-side copy) to: empty space
2026/03/01 03:18:42 INFO  : empty space.rclone_chunk.001_7g8aoi: Deleted
2026/03/01 03:18:42 DEBUG : potato3.rclone_chunk.001: size = 50 OK
2026/03/01 03:18:42 DEBUG : potato3.rclone_chunk.001_7g8bxn: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2026/03/01 03:18:42 INFO  : potato3.rclone_chunk.001_7g8bxn: Copied (server-side copy) to: potato3.rclone_chunk.001
2026/03/01 03:18:42 INFO  : potato3.rclone_chunk.001_7g8bxn: Deleted
2026/03/01 03:18:42 DEBUG : potato3.rclone_chunk.002: size = 18 OK
2026/03/01 03:18:42 DEBUG : potato3.rclone_chunk.002_7g8bxn: md5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2026/03/01 03:18:42 INFO  : potato3.rclone_chunk.002_7g8bxn: Copied (server-side copy) to: potato3.rclone_chunk.002
2026/03/01 03:18:42 INFO  : potato3.rclone_chunk.002_7g8bxn: Deleted
    sync_test.go:1736: Server side move (if possible) Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-sogixuv9gala' -> Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xuxadeh8koko'
2026/03/01 03:18:43 INFO  : S3 bucket rclone-test-xuxadeh8koko: Bucket "rclone-test-xuxadeh8koko" created with ACL ""
2026/03/01 03:18:44 DEBUG : empty space: size = 1 OK
2026/03/01 03:18:44 DEBUG : empty space.rclone_chunk.001_7g8c0n: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2026/03/01 03:18:44 INFO  : empty space.rclone_chunk.001_7g8c0n: Copied (server-side copy) to: empty space
2026/03/01 03:18:44 INFO  : empty space.rclone_chunk.001_7g8c0n: Deleted
2026/03/01 03:18:44 DEBUG : potato3.rclone_chunk.001: size = 50 OK
2026/03/01 03:18:44 DEBUG : potato3.rclone_chunk.001_7g8duy: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2026/03/01 03:18:44 INFO  : potato3.rclone_chunk.001_7g8duy: Copied (server-side copy) to: potato3.rclone_chunk.001
2026/03/01 03:18:44 INFO  : potato3.rclone_chunk.001_7g8duy: Deleted
2026/03/01 03:18:44 DEBUG : potato3.rclone_chunk.002: size = 10 OK
2026/03/01 03:18:44 DEBUG : potato3.rclone_chunk.002_7g8duy: md5 = 01a6651a94ab602dbfc47587d234b52c OK
2026/03/01 03:18:44 INFO  : potato3.rclone_chunk.002_7g8duy: Copied (server-side copy) to: potato3.rclone_chunk.002
2026/03/01 03:18:44 INFO  : potato3.rclone_chunk.002_7g8duy: Deleted
2026/03/01 03:18:45 DEBUG : potato2: Need to transfer - File not found at Destination
2026/03/01 03:18:45 DEBUG : empty space: size = 1 OK
2026/03/01 03:18:45 DEBUG : potato3: size = 68 (Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-sogixuv9gala')
2026/03/01 03:18:45 DEBUG : potato3: size = 60 (Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xuxadeh8koko')
2026/03/01 03:18:45 DEBUG : potato3: Sizes differ
2026/03/01 03:18:45 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xuxadeh8koko': Waiting for checks to finish
2026/03/01 03:18:45 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2026/03/01 03:18:45 DEBUG : empty space: Unchanged skipping
2026/03/01 03:18:45 DEBUG : potato2: Can't copy - required hash not found
2026/03/01 03:18:45 DEBUG : potato3: Can't copy - required hash not found
2026/03/01 03:18:45 INFO  : empty space: Deleted
2026/03/01 03:18:45 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xuxadeh8koko': Waiting for transfers to finish
2026/03/01 03:18:45 DEBUG : potato2.rclone_chunk.001: size = 50 OK
2026/03/01 03:18:45 DEBUG : potato2.rclone_chunk.001_7g8e1z: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2026/03/01 03:18:45 INFO  : potato2.rclone_chunk.001_7g8e1z: Copied (server-side copy) to: potato2.rclone_chunk.001
2026/03/01 03:18:45 INFO  : potato2.rclone_chunk.001_7g8e1z: Deleted
2026/03/01 03:18:46 DEBUG : potato2.rclone_chunk.002: size = 10 OK
2026/03/01 03:18:46 DEBUG : potato2.rclone_chunk.002_7g8e1z: md5 = 01a6651a94ab602dbfc47587d234b52c OK
2026/03/01 03:18:46 INFO  : potato2.rclone_chunk.002_7g8e1z: Copied (server-side copy) to: potato2.rclone_chunk.002
2026/03/01 03:18:46 DEBUG : potato3.rclone_chunk.001: size = 50 OK
2026/03/01 03:18:46 DEBUG : potato3.rclone_chunk.001_7g8ef7: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2026/03/01 03:18:46 INFO  : potato3.rclone_chunk.001_7g8ef7: Copied (server-side copy) to: potato3.rclone_chunk.001
2026/03/01 03:18:46 INFO  : potato2.rclone_chunk.002_7g8e1z: Deleted
2026/03/01 03:18:46 INFO  : potato3.rclone_chunk.001_7g8ef7: Deleted
2026/03/01 03:18:46 DEBUG : potato2: size = 60 OK
2026/03/01 03:18:46 INFO  : potato2: Copied (new)
2026/03/01 03:18:46 DEBUG : potato3.rclone_chunk.002: size = 18 OK
2026/03/01 03:18:46 DEBUG : potato3.rclone_chunk.002_7g8ef7: md5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2026/03/01 03:18:46 INFO  : potato3.rclone_chunk.002_7g8ef7: Copied (server-side copy) to: potato3.rclone_chunk.002
2026/03/01 03:18:46 INFO  : potato3.rclone_chunk.002_7g8ef7: Deleted
2026/03/01 03:18:46 INFO  : potato2: Deleted
2026/03/01 03:18:46 DEBUG : potato3: size = 68 OK
2026/03/01 03:18:46 INFO  : potato3: Copied (replaced existing)
2026/03/01 03:18:46 INFO  : potato3: Deleted
2026/03/01 03:18:46 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-fovoxoh2ludi"
2026/03/01 03:18:46 DEBUG : Creating backend with remote "TestS3:rclone-test-fovoxoh2ludi"
2026/03/01 03:18:46 DEBUG : empty space: Need to transfer - File not found at Destination
2026/03/01 03:18:46 DEBUG : potato2: Need to transfer - File not found at Destination
2026/03/01 03:18:46 DEBUG : potato3: Need to transfer - File not found at Destination
2026/03/01 03:18:46 DEBUG : empty space: copy non-chunked object...
2026/03/01 03:18:46 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-fovoxoh2ludi': Waiting for checks to finish
2026/03/01 03:18:46 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-fovoxoh2ludi': Waiting for transfers to finish
2026/03/01 03:18:47 DEBUG : potato3: Can't copy - required hash not found
2026/03/01 03:18:47 DEBUG : potato2: Can't copy - required hash not found
2026/03/01 03:18:47 INFO  : S3 bucket rclone-test-fovoxoh2ludi: Bucket "rclone-test-fovoxoh2ludi" created with ACL ""
2026/03/01 03:18:47 DEBUG : empty space: size = 1 OK
2026/03/01 03:18:47 INFO  : empty space: Copied (server-side copy)
2026/03/01 03:18:47 INFO  : empty space: Deleted
2026/03/01 03:18:47 DEBUG : potato3.rclone_chunk.001: size = 50 OK
2026/03/01 03:18:47 DEBUG : potato3.rclone_chunk.001_7g8g30: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2026/03/01 03:18:47 INFO  : potato3.rclone_chunk.001_7g8g30: Copied (server-side copy) to: potato3.rclone_chunk.001
2026/03/01 03:18:47 DEBUG : potato2.rclone_chunk.001: size = 50 OK
2026/03/01 03:18:47 DEBUG : potato2.rclone_chunk.001_7g8gml: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2026/03/01 03:18:47 INFO  : potato2.rclone_chunk.001_7g8gml: Copied (server-side copy) to: potato2.rclone_chunk.001
2026/03/01 03:18:48 INFO  : potato3.rclone_chunk.001_7g8g30: Deleted
2026/03/01 03:18:48 INFO  : potato2.rclone_chunk.001_7g8gml: Deleted
2026/03/01 03:18:48 DEBUG : potato2.rclone_chunk.002: size = 10 OK
2026/03/01 03:18:48 DEBUG : potato2.rclone_chunk.002_7g8gml: md5 = 01a6651a94ab602dbfc47587d234b52c OK
2026/03/01 03:18:48 INFO  : potato2.rclone_chunk.002_7g8gml: Copied (server-side copy) to: potato2.rclone_chunk.002
2026/03/01 03:18:48 DEBUG : potato3.rclone_chunk.002: size = 18 OK
2026/03/01 03:18:48 DEBUG : potato3.rclone_chunk.002_7g8g30: md5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2026/03/01 03:18:48 INFO  : potato3.rclone_chunk.002_7g8g30: Copied (server-side copy) to: potato3.rclone_chunk.002
2026/03/01 03:18:48 INFO  : potato3.rclone_chunk.002_7g8g30: Deleted
2026/03/01 03:18:48 INFO  : potato2.rclone_chunk.002_7g8gml: Deleted
2026/03/01 03:18:48 DEBUG : potato3: size = 68 OK
2026/03/01 03:18:48 INFO  : potato3: Copied (new)
2026/03/01 03:18:48 DEBUG : potato2: size = 60 OK
2026/03/01 03:18:48 INFO  : potato2: Copied (new)
2026/03/01 03:18:48 INFO  : potato3: Deleted
2026/03/01 03:18:48 INFO  : potato2: Deleted
2026/03/01 03:18:48 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-fovoxoh2ludi': Purge remote
2026/03/01 03:18:48 DEBUG : S3 bucket rclone-test-fovoxoh2ludi: bucket is versioned: false
2026/03/01 03:18:48 DEBUG : Waiting for deletions to finish
2026/03/01 03:18:48 DEBUG : "empty space" version false
2026/03/01 03:18:48 DEBUG : empty space: Deleting (id "")
2026/03/01 03:18:48 DEBUG : "potato2" version false
2026/03/01 03:18:48 DEBUG : potato2: Deleting (id "")
2026/03/01 03:18:48 DEBUG : "potato2.rclone_chunk.001" version false
2026/03/01 03:18:48 DEBUG : potato2.rclone_chunk.001: Deleting (id "")
2026/03/01 03:18:48 DEBUG : "potato2.rclone_chunk.002" version false
2026/03/01 03:18:48 DEBUG : potato2.rclone_chunk.002: Deleting (id "")
2026/03/01 03:18:48 DEBUG : "potato3" version false
2026/03/01 03:18:48 DEBUG : potato3: Deleting (id "")
2026/03/01 03:18:48 DEBUG : "potato3.rclone_chunk.001" version false
2026/03/01 03:18:48 DEBUG : potato3.rclone_chunk.001: Deleting (id "")
2026/03/01 03:18:48 DEBUG : "potato3.rclone_chunk.002" version false
2026/03/01 03:18:48 DEBUG : potato3.rclone_chunk.002: Deleting (id "")
2026/03/01 03:18:48 INFO  : potato3: Deleted
2026/03/01 03:18:48 INFO  : potato2: Deleted
2026/03/01 03:18:48 INFO  : empty space: Deleted
2026/03/01 03:18:48 INFO  : potato2.rclone_chunk.001: Deleted
2026/03/01 03:18:48 INFO  : potato3.rclone_chunk.002: Deleted
2026/03/01 03:18:48 INFO  : potato2.rclone_chunk.002: Deleted
2026/03/01 03:18:48 INFO  : potato3.rclone_chunk.001: Deleted
2026/03/01 03:18:49 INFO  : S3 bucket rclone-test-fovoxoh2ludi: Bucket "rclone-test-fovoxoh2ludi" deleted
2026/03/01 03:18:49 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xuxadeh8koko': Purge remote
2026/03/01 03:18:49 DEBUG : S3 bucket rclone-test-xuxadeh8koko: bucket is versioned: false
2026/03/01 03:18:49 DEBUG : Waiting for deletions to finish
2026/03/01 03:18:49 INFO  : S3 bucket rclone-test-xuxadeh8koko: Bucket "rclone-test-xuxadeh8koko" deleted
--- PASS: TestServerSideMove (9.46s)
PASS
2026/03/01 03:18:49 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-sogixuv9gala': Purge remote
2026/03/01 03:18:49 DEBUG : S3 bucket rclone-test-sogixuv9gala: bucket is versioned: false
2026/03/01 03:18:49 DEBUG : Waiting for deletions to finish
2026/03/01 03:18:50 INFO  : S3 bucket rclone-test-sogixuv9gala: Bucket "rclone-test-sogixuv9gala" deleted
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -fast-list -size-limit 1024 -test.run '^TestServerSideMove$'" - Finished OK in 9.903353588s (try 2/5)