"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMove$'" - Starting (try 2/5) 2026/01/12 01:20:04 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-xuqefaj1qaza" 2026/01/12 01:20:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/12 01:20:04 DEBUG : Creating backend with remote "TestS3:rclone-test-xuqefaj1qaza" 2026/01/12 01:20:04 DEBUG : Creating backend with remote "/tmp/rclone2137124928" === RUN TestServerSideMove run.go:185: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xuqefaj1qaza'", Local "Local file system at /tmp/rclone2137124928", Modify Window "1ns" 2026/01/12 01:20:04 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-liwakeb9rexo" 2026/01/12 01:20:04 DEBUG : Creating backend with remote "TestS3:rclone-test-liwakeb9rexo" 2026/01/12 01:20:05 INFO : S3 bucket rclone-test-xuqefaj1qaza: Bucket "rclone-test-xuqefaj1qaza" created with ACL "" 2026/01/12 01:20:05 DEBUG : potato2.rclone_chunk.001: size = 50 OK 2026/01/12 01:20:05 DEBUG : potato2.rclone_chunk.001_qeq12i: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2026/01/12 01:20:05 INFO : potato2.rclone_chunk.001_qeq12i: Copied (server-side copy) to: potato2.rclone_chunk.001 2026/01/12 01:20:06 INFO : potato2.rclone_chunk.001_qeq12i: Deleted 2026/01/12 01:20:06 DEBUG : potato2.rclone_chunk.002: size = 10 OK 2026/01/12 01:20:06 DEBUG : potato2.rclone_chunk.002_qeq12i: md5 = 01a6651a94ab602dbfc47587d234b52c OK 2026/01/12 01:20:06 INFO : potato2.rclone_chunk.002_qeq12i: Copied (server-side copy) to: potato2.rclone_chunk.002 2026/01/12 01:20:06 INFO : potato2.rclone_chunk.002_qeq12i: Deleted 2026/01/12 01:20:06 DEBUG : empty space: size = 1 OK 2026/01/12 01:20:06 DEBUG : empty space.rclone_chunk.001_qeq2in: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2026/01/12 01:20:06 INFO : empty space.rclone_chunk.001_qeq2in: Copied (server-side copy) to: empty space 2026/01/12 01:20:06 INFO : empty space.rclone_chunk.001_qeq2in: Deleted 2026/01/12 01:20:07 DEBUG : potato3.rclone_chunk.001: size = 50 OK 2026/01/12 01:20:07 DEBUG : potato3.rclone_chunk.001_qeq2n6: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2026/01/12 01:20:07 INFO : potato3.rclone_chunk.001_qeq2n6: Copied (server-side copy) to: potato3.rclone_chunk.001 2026/01/12 01:20:07 INFO : potato3.rclone_chunk.001_qeq2n6: Deleted 2026/01/12 01:20:07 DEBUG : potato3.rclone_chunk.002: size = 18 OK 2026/01/12 01:20:07 DEBUG : potato3.rclone_chunk.002_qeq2n6: md5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2026/01/12 01:20:07 INFO : potato3.rclone_chunk.002_qeq2n6: Copied (server-side copy) to: potato3.rclone_chunk.002 2026/01/12 01:20:07 INFO : potato3.rclone_chunk.002_qeq2n6: Deleted sync_test.go:1736: Server side move (if possible) Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xuqefaj1qaza' -> Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-liwakeb9rexo' 2026/01/12 01:20:08 INFO : S3 bucket rclone-test-liwakeb9rexo: Bucket "rclone-test-liwakeb9rexo" created with ACL "" 2026/01/12 01:20:08 DEBUG : empty space: size = 1 OK 2026/01/12 01:20:08 DEBUG : empty space.rclone_chunk.001_qeq4qx: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2026/01/12 01:20:08 INFO : empty space.rclone_chunk.001_qeq4qx: Copied (server-side copy) to: empty space 2026/01/12 01:20:08 INFO : empty space.rclone_chunk.001_qeq4qx: Deleted 2026/01/12 01:20:09 DEBUG : potato3.rclone_chunk.001: size = 50 OK 2026/01/12 01:20:09 DEBUG : potato3.rclone_chunk.001_qeq4zo: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2026/01/12 01:20:09 INFO : potato3.rclone_chunk.001_qeq4zo: Copied (server-side copy) to: potato3.rclone_chunk.001 2026/01/12 01:20:09 INFO : potato3.rclone_chunk.001_qeq4zo: Deleted 2026/01/12 01:20:09 DEBUG : potato3.rclone_chunk.002: size = 10 OK 2026/01/12 01:20:09 DEBUG : potato3.rclone_chunk.002_qeq4zo: md5 = 01a6651a94ab602dbfc47587d234b52c OK 2026/01/12 01:20:09 INFO : potato3.rclone_chunk.002_qeq4zo: Copied (server-side copy) to: potato3.rclone_chunk.002 2026/01/12 01:20:09 INFO : potato3.rclone_chunk.002_qeq4zo: Deleted 2026/01/12 01:20:09 DEBUG : potato2: Need to transfer - File not found at Destination 2026/01/12 01:20:09 DEBUG : empty space: size = 1 OK 2026/01/12 01:20:09 DEBUG : potato3: size = 68 (Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xuqefaj1qaza') 2026/01/12 01:20:09 DEBUG : potato3: size = 60 (Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-liwakeb9rexo') 2026/01/12 01:20:09 DEBUG : potato3: Sizes differ 2026/01/12 01:20:09 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-liwakeb9rexo': Waiting for checks to finish 2026/01/12 01:20:09 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2026/01/12 01:20:09 DEBUG : empty space: Unchanged skipping 2026/01/12 01:20:09 DEBUG : potato2: Can't copy - required hash not found 2026/01/12 01:20:09 DEBUG : potato3: Can't copy - required hash not found 2026/01/12 01:20:09 INFO : empty space: Deleted 2026/01/12 01:20:09 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-liwakeb9rexo': Waiting for transfers to finish 2026/01/12 01:20:10 DEBUG : potato2.rclone_chunk.001: size = 50 OK 2026/01/12 01:20:10 DEBUG : potato2.rclone_chunk.001_qeq5kd: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2026/01/12 01:20:10 INFO : potato2.rclone_chunk.001_qeq5kd: Copied (server-side copy) to: potato2.rclone_chunk.001 2026/01/12 01:20:10 INFO : potato2.rclone_chunk.001_qeq5kd: Deleted 2026/01/12 01:20:10 DEBUG : potato3.rclone_chunk.001: size = 50 OK 2026/01/12 01:20:10 DEBUG : potato3.rclone_chunk.001_qeq5o7: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2026/01/12 01:20:10 INFO : potato3.rclone_chunk.001_qeq5o7: Copied (server-side copy) to: potato3.rclone_chunk.001 2026/01/12 01:20:10 INFO : potato3.rclone_chunk.001_qeq5o7: Deleted 2026/01/12 01:20:10 DEBUG : potato2.rclone_chunk.002: size = 10 OK 2026/01/12 01:20:10 DEBUG : potato2.rclone_chunk.002_qeq5kd: md5 = 01a6651a94ab602dbfc47587d234b52c OK 2026/01/12 01:20:10 INFO : potato2.rclone_chunk.002_qeq5kd: Copied (server-side copy) to: potato2.rclone_chunk.002 2026/01/12 01:20:10 INFO : potato2.rclone_chunk.002_qeq5kd: Deleted 2026/01/12 01:20:10 DEBUG : potato3.rclone_chunk.002: size = 18 OK 2026/01/12 01:20:10 DEBUG : potato3.rclone_chunk.002_qeq5o7: md5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2026/01/12 01:20:10 INFO : potato3.rclone_chunk.002_qeq5o7: Copied (server-side copy) to: potato3.rclone_chunk.002 2026/01/12 01:20:10 INFO : potato3.rclone_chunk.002_qeq5o7: Deleted 2026/01/12 01:20:10 DEBUG : potato2: size = 60 OK 2026/01/12 01:20:10 INFO : potato2: Copied (new) 2026/01/12 01:20:10 DEBUG : potato3: size = 68 OK 2026/01/12 01:20:10 INFO : potato3: Copied (replaced existing) 2026/01/12 01:20:10 INFO : potato2: Deleted 2026/01/12 01:20:10 INFO : potato3: Deleted 2026/01/12 01:20:10 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-jotevuv8pije" 2026/01/12 01:20:10 DEBUG : Creating backend with remote "TestS3:rclone-test-jotevuv8pije" 2026/01/12 01:20:11 DEBUG : empty space: Need to transfer - File not found at Destination 2026/01/12 01:20:11 DEBUG : potato2: Need to transfer - File not found at Destination 2026/01/12 01:20:11 DEBUG : potato3: Need to transfer - File not found at Destination 2026/01/12 01:20:11 DEBUG : empty space: copy non-chunked object... 2026/01/12 01:20:11 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-jotevuv8pije': Waiting for checks to finish 2026/01/12 01:20:11 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-jotevuv8pije': Waiting for transfers to finish 2026/01/12 01:20:11 DEBUG : potato2: Can't copy - required hash not found 2026/01/12 01:20:11 DEBUG : potato3: Can't copy - required hash not found 2026/01/12 01:20:11 INFO : S3 bucket rclone-test-jotevuv8pije: Bucket "rclone-test-jotevuv8pije" created with ACL "" 2026/01/12 01:20:11 DEBUG : empty space: size = 1 OK 2026/01/12 01:20:11 INFO : empty space: Copied (server-side copy) 2026/01/12 01:20:11 INFO : empty space: Deleted 2026/01/12 01:20:12 DEBUG : potato2.rclone_chunk.001: size = 50 OK 2026/01/12 01:20:12 DEBUG : potato2.rclone_chunk.001_qeq7hh: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2026/01/12 01:20:12 INFO : potato2.rclone_chunk.001_qeq7hh: Copied (server-side copy) to: potato2.rclone_chunk.001 2026/01/12 01:20:12 DEBUG : potato3.rclone_chunk.001: size = 50 OK 2026/01/12 01:20:12 DEBUG : potato3.rclone_chunk.001_qeq7ub: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2026/01/12 01:20:12 INFO : potato3.rclone_chunk.001_qeq7ub: Copied (server-side copy) to: potato3.rclone_chunk.001 2026/01/12 01:20:12 INFO : potato2.rclone_chunk.001_qeq7hh: Deleted 2026/01/12 01:20:12 INFO : potato3.rclone_chunk.001_qeq7ub: Deleted 2026/01/12 01:20:12 DEBUG : potato2.rclone_chunk.002: size = 10 OK 2026/01/12 01:20:12 DEBUG : potato2.rclone_chunk.002_qeq7hh: md5 = 01a6651a94ab602dbfc47587d234b52c OK 2026/01/12 01:20:12 INFO : potato2.rclone_chunk.002_qeq7hh: Copied (server-side copy) to: potato2.rclone_chunk.002 2026/01/12 01:20:12 DEBUG : potato3.rclone_chunk.002: size = 18 OK 2026/01/12 01:20:12 DEBUG : potato3.rclone_chunk.002_qeq7ub: md5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2026/01/12 01:20:12 INFO : potato3.rclone_chunk.002_qeq7ub: Copied (server-side copy) to: potato3.rclone_chunk.002 2026/01/12 01:20:12 INFO : potato2.rclone_chunk.002_qeq7hh: Deleted 2026/01/12 01:20:12 INFO : potato3.rclone_chunk.002_qeq7ub: Deleted 2026/01/12 01:20:12 DEBUG : potato2: size = 60 OK 2026/01/12 01:20:12 INFO : potato2: Copied (new) 2026/01/12 01:20:12 DEBUG : potato3: size = 68 OK 2026/01/12 01:20:12 INFO : potato3: Copied (new) 2026/01/12 01:20:12 INFO : potato2: Deleted 2026/01/12 01:20:12 INFO : potato3: Deleted 2026/01/12 01:20:12 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-jotevuv8pije': Purge remote 2026/01/12 01:20:12 DEBUG : S3 bucket rclone-test-jotevuv8pije: bucket is versioned: false 2026/01/12 01:20:12 DEBUG : Waiting for deletions to finish 2026/01/12 01:20:12 DEBUG : "empty space" version false 2026/01/12 01:20:12 DEBUG : empty space: Deleting (id "") 2026/01/12 01:20:12 DEBUG : "potato2" version false 2026/01/12 01:20:12 DEBUG : potato2: Deleting (id "") 2026/01/12 01:20:12 DEBUG : "potato2.rclone_chunk.001" version false 2026/01/12 01:20:12 DEBUG : potato2.rclone_chunk.001: Deleting (id "") 2026/01/12 01:20:12 DEBUG : "potato2.rclone_chunk.002" version false 2026/01/12 01:20:12 DEBUG : potato2.rclone_chunk.002: Deleting (id "") 2026/01/12 01:20:12 DEBUG : "potato3" version false 2026/01/12 01:20:12 DEBUG : potato3: Deleting (id "") 2026/01/12 01:20:12 DEBUG : "potato3.rclone_chunk.001" version false 2026/01/12 01:20:12 DEBUG : potato3.rclone_chunk.001: Deleting (id "") 2026/01/12 01:20:12 DEBUG : "potato3.rclone_chunk.002" version false 2026/01/12 01:20:12 DEBUG : potato3.rclone_chunk.002: Deleting (id "") 2026/01/12 01:20:12 INFO : potato2.rclone_chunk.002: Deleted 2026/01/12 01:20:12 INFO : empty space: Deleted 2026/01/12 01:20:12 INFO : potato2.rclone_chunk.001: Deleted 2026/01/12 01:20:13 INFO : potato2: Deleted 2026/01/12 01:20:13 INFO : potato3.rclone_chunk.001: Deleted 2026/01/12 01:20:13 INFO : potato3: Deleted 2026/01/12 01:20:13 INFO : potato3.rclone_chunk.002: Deleted 2026/01/12 01:20:13 INFO : S3 bucket rclone-test-jotevuv8pije: Bucket "rclone-test-jotevuv8pije" deleted 2026/01/12 01:20:13 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-liwakeb9rexo': Purge remote 2026/01/12 01:20:13 DEBUG : S3 bucket rclone-test-liwakeb9rexo: bucket is versioned: false 2026/01/12 01:20:13 DEBUG : Waiting for deletions to finish 2026/01/12 01:20:13 INFO : S3 bucket rclone-test-liwakeb9rexo: Bucket "rclone-test-liwakeb9rexo" deleted --- PASS: TestServerSideMove (9.08s) PASS 2026/01/12 01:20:13 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-xuqefaj1qaza': Purge remote 2026/01/12 01:20:13 DEBUG : S3 bucket rclone-test-xuqefaj1qaza: bucket is versioned: false 2026/01/12 01:20:13 DEBUG : Waiting for deletions to finish 2026/01/12 01:20:14 INFO : S3 bucket rclone-test-xuqefaj1qaza: Bucket "rclone-test-xuqefaj1qaza" deleted "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMove$'" - Finished OK in 9.52068057s (try 2/5)