"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMove$'" - Starting (try 2/5) 2020/12/12 05:31:44 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-gapipij8voyikev7lalemuk4" 2020/12/12 05:31:44 DEBUG : Creating backend with remote "TestS3:rclone-test-gapipij8voyikev7lalemuk4" 2020/12/12 05:31:44 DEBUG : Reset feature "ListR" 2020/12/12 05:31:44 DEBUG : Creating backend with remote "/tmp/rclone497251079" === RUN TestServerSideMove run.go:176: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-gapipij8voyikev7lalemuk4'", Local "Local file system at /tmp/rclone497251079", Modify Window "1ns" 2020/12/12 05:31:44 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-bakekin7betewat5qorepis4" 2020/12/12 05:31:44 DEBUG : Creating backend with remote "TestS3:rclone-test-bakekin7betewat5qorepis4" 2020/12/12 05:31:44 DEBUG : Reset feature "ListR" 2020/12/12 05:31:45 INFO : S3 bucket rclone-test-gapipij8voyikev7lalemuk4: Bucket "rclone-test-gapipij8voyikev7lalemuk4" created with ACL "private" 2020/12/12 05:31:45 DEBUG : potato2.rclone_chunk.001_7tv08q: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:31:45 INFO : potato2.rclone_chunk.001_7tv08q: Copied (server-side copy) to: potato2.rclone_chunk.001 2020/12/12 05:31:45 INFO : potato2.rclone_chunk.001_7tv08q: Deleted 2020/12/12 05:31:45 DEBUG : potato2.rclone_chunk.002_7tv08q: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:31:45 INFO : potato2.rclone_chunk.002_7tv08q: Copied (server-side copy) to: potato2.rclone_chunk.002 2020/12/12 05:31:45 INFO : potato2.rclone_chunk.002_7tv08q: Deleted 2020/12/12 05:31:46 DEBUG : empty space.rclone_chunk.001_7tv0ld: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:31:46 INFO : empty space.rclone_chunk.001_7tv0ld: Copied (server-side copy) to: empty space 2020/12/12 05:31:46 INFO : empty space.rclone_chunk.001_7tv0ld: Deleted 2020/12/12 05:31:46 DEBUG : potato3.rclone_chunk.001_7tv19j: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:31:46 INFO : potato3.rclone_chunk.001_7tv19j: Copied (server-side copy) to: potato3.rclone_chunk.001 2020/12/12 05:31:46 INFO : potato3.rclone_chunk.001_7tv19j: Deleted 2020/12/12 05:31:46 DEBUG : potato3.rclone_chunk.002_7tv19j: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/12/12 05:31:46 INFO : potato3.rclone_chunk.002_7tv19j: Copied (server-side copy) to: potato3.rclone_chunk.002 2020/12/12 05:31:46 INFO : potato3.rclone_chunk.002_7tv19j: Deleted sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-gapipij8voyikev7lalemuk4' -> Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-bakekin7betewat5qorepis4' 2020/12/12 05:31:47 INFO : S3 bucket rclone-test-bakekin7betewat5qorepis4: Bucket "rclone-test-bakekin7betewat5qorepis4" created with ACL "private" 2020/12/12 05:31:47 DEBUG : empty space.rclone_chunk.001_7tv2zm: MD5 = 336d5ebc5436534e61d16e63ddfca327 OK 2020/12/12 05:31:47 INFO : empty space.rclone_chunk.001_7tv2zm: Copied (server-side copy) to: empty space 2020/12/12 05:31:47 INFO : empty space.rclone_chunk.001_7tv2zm: Deleted 2020/12/12 05:31:47 DEBUG : potato3.rclone_chunk.001_7tv2hc: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:31:47 INFO : potato3.rclone_chunk.001_7tv2hc: Copied (server-side copy) to: potato3.rclone_chunk.001 2020/12/12 05:31:47 INFO : potato3.rclone_chunk.001_7tv2hc: Deleted 2020/12/12 05:31:47 DEBUG : potato3.rclone_chunk.002_7tv2hc: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:31:47 INFO : potato3.rclone_chunk.002_7tv2hc: Copied (server-side copy) to: potato3.rclone_chunk.002 2020/12/12 05:31:47 INFO : potato3.rclone_chunk.002_7tv2hc: Deleted 2020/12/12 05:31:48 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/12/12 05:31:48 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-bakekin7betewat5qorepis4': Waiting for checks to finish 2020/12/12 05:31:48 DEBUG : potato3: Can't copy - required hash not found 2020/12/12 05:31:48 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/12/12 05:31:48 DEBUG : empty space: Unchanged skipping 2020/12/12 05:31:48 DEBUG : potato2: Can't copy - required hash not found 2020/12/12 05:31:48 INFO : empty space: Deleted 2020/12/12 05:31:48 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-bakekin7betewat5qorepis4': Waiting for transfers to finish 2020/12/12 05:31:48 DEBUG : potato2.rclone_chunk.001_7tv36o: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:31:48 INFO : potato2.rclone_chunk.001_7tv36o: Copied (server-side copy) to: potato2.rclone_chunk.001 2020/12/12 05:31:48 INFO : potato2.rclone_chunk.001_7tv36o: Deleted 2020/12/12 05:31:48 DEBUG : potato2.rclone_chunk.002_7tv36o: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:31:48 INFO : potato2.rclone_chunk.002_7tv36o: Copied (server-side copy) to: potato2.rclone_chunk.002 2020/12/12 05:31:48 INFO : potato2.rclone_chunk.002_7tv36o: Deleted 2020/12/12 05:31:48 DEBUG : potato3.rclone_chunk.001_7tv363: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:31:48 INFO : potato3.rclone_chunk.001_7tv363: Copied (server-side copy) to: potato3.rclone_chunk.001 2020/12/12 05:31:48 INFO : potato3.rclone_chunk.001_7tv363: Deleted 2020/12/12 05:31:48 INFO : potato2: Copied (new) 2020/12/12 05:31:48 DEBUG : potato3.rclone_chunk.002_7tv363: MD5 = 111c387f2584838efb7e0ec6f2fa2e10 OK 2020/12/12 05:31:48 INFO : potato3.rclone_chunk.002_7tv363: Copied (server-side copy) to: potato3.rclone_chunk.002 2020/12/12 05:31:48 INFO : potato3.rclone_chunk.002_7tv363: Deleted 2020/12/12 05:31:48 INFO : potato2: Deleted 2020/12/12 05:31:48 INFO : potato3: Copied (replaced existing) 2020/12/12 05:31:48 INFO : potato3: Deleted 2020/12/12 05:31:48 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-gabuwih8kesocek9tololiv3" 2020/12/12 05:31:48 DEBUG : Creating backend with remote "TestS3:rclone-test-gabuwih8kesocek9tololiv3" 2020/12/12 05:31:48 DEBUG : Reset feature "ListR" 2020/12/12 05:31:48 DEBUG : empty space: copy non-chunked object... 2020/12/12 05:31:48 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-gabuwih8kesocek9tololiv3': Waiting for checks to finish 2020/12/12 05:31:48 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-gabuwih8kesocek9tololiv3': Waiting for transfers to finish 2020/12/12 05:31:48 DEBUG : potato2: Can't copy - required hash not found 2020/12/12 05:31:48 DEBUG : potato3: Can't copy - required hash not found 2020/12/12 05:31:49 INFO : S3 bucket rclone-test-gabuwih8kesocek9tololiv3: Bucket "rclone-test-gabuwih8kesocek9tololiv3" created with ACL "private" 2020/12/12 05:31:49 INFO : empty space: Copied (server-side copy) 2020/12/12 05:31:49 ERROR : potato3: Failed to copy: object not found 2020/12/12 05:31:49 ERROR : potato3: Not deleting source as copy failed: object not found 2020/12/12 05:31:49 INFO : empty space: Deleted 2020/12/12 05:31:49 DEBUG : potato2.rclone_chunk.001_7tv33c: MD5 = dbbc040ac83a6f6c6816767a36d34c7b OK 2020/12/12 05:31:49 INFO : potato2.rclone_chunk.001_7tv33c: Copied (server-side copy) to: potato2.rclone_chunk.001 2020/12/12 05:31:49 INFO : potato2.rclone_chunk.001_7tv33c: Deleted 2020/12/12 05:31:49 DEBUG : potato2.rclone_chunk.002_7tv33c: MD5 = 01a6651a94ab602dbfc47587d234b52c OK 2020/12/12 05:31:49 INFO : potato2.rclone_chunk.002_7tv33c: Copied (server-side copy) to: potato2.rclone_chunk.002 2020/12/12 05:31:49 INFO : potato2.rclone_chunk.002_7tv33c: Deleted 2020/12/12 05:31:49 INFO : potato2: Copied (new) 2020/12/12 05:31:49 INFO : potato2: Deleted sync_test.go:1331: Error Trace: sync_test.go:1331 sync_test.go:1398 Error: Received unexpected error: object not found Test: TestServerSideMove 2020/12/12 05:31:50 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-gabuwih8kesocek9tololiv3': Purge object "empty space" 2020/12/12 05:31:50 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-gabuwih8kesocek9tololiv3': Purge object "potato2" 2020/12/12 05:31:50 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-gabuwih8kesocek9tololiv3': Purge dir "" 2020/12/12 05:31:50 purge failed to rmdir "": BucketNotEmpty: The bucket you tried to delete is not empty status code: 409, request id: 75A422FA1C0B7254, host id: dZR57hoD+6yPkdtY2STGmHQ/aCJvd5lLfAKwqS/jd/16zc43+AZzsyq8pWG5eCa6quXS7qO1vPQ= 2020/12/12 05:31:50 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-bakekin7betewat5qorepis4': Purge object "potato3" 2020/12/12 05:31:50 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-bakekin7betewat5qorepis4': Purge dir "" 2020/12/12 05:31:50 INFO : S3 bucket rclone-test-bakekin7betewat5qorepis4: Bucket "rclone-test-bakekin7betewat5qorepis4" deleted --- FAIL: TestServerSideMove (5.83s) FAIL 2020/12/12 05:31:50 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-gapipij8voyikev7lalemuk4': Purge dir "" 2020/12/12 05:31:50 INFO : S3 bucket rclone-test-gapipij8voyikev7lalemuk4: Bucket "rclone-test-gapipij8voyikev7lalemuk4" deleted "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMove$'" - Finished ERROR in 6.144567129s (try 2/5): exit status 1: Failed [TestServerSideMove]