"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMoveWithFilter$'" - Starting (try 2/5)
2025/10/28 04:40:47 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-tunitok6hafe"
2025/10/28 04:40:47 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/28 04:40:47 DEBUG : Creating backend with remote "TestS3:rclone-test-tunitok6hafe"
2025/10/28 04:40:47 DEBUG : Creating backend with remote "/tmp/rclone4163801103"
=== RUN   TestServerSideMoveWithFilter
    run.go:180: Remote "Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tunitok6hafe'", Local "Local file system at /tmp/rclone4163801103", Modify Window "1ns"
2025/10/28 04:40:47 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-sunebag1loxo"
2025/10/28 04:40:47 DEBUG : Creating backend with remote "TestS3:rclone-test-sunebag1loxo"
2025/10/28 04:40:48 INFO  : S3 bucket rclone-test-tunitok6hafe: Bucket "rclone-test-tunitok6hafe" created with ACL ""
2025/10/28 04:40:48 DEBUG : potato2.rclone_chunk.001_txbstq: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2025/10/28 04:40:48 INFO  : potato2.rclone_chunk.001_txbstq: Copied (server-side copy) to: potato2.rclone_chunk.001
2025/10/28 04:40:48 INFO  : potato2.rclone_chunk.001_txbstq: Deleted
2025/10/28 04:40:48 DEBUG : potato2.rclone_chunk.002_txbstq: md5 = 01a6651a94ab602dbfc47587d234b52c OK
2025/10/28 04:40:48 INFO  : potato2.rclone_chunk.002_txbstq: Copied (server-side copy) to: potato2.rclone_chunk.002
2025/10/28 04:40:48 INFO  : potato2.rclone_chunk.002_txbstq: Deleted
2025/10/28 04:40:49 DEBUG : empty space.rclone_chunk.001_txbsq4: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2025/10/28 04:40:49 INFO  : empty space.rclone_chunk.001_txbsq4: Copied (server-side copy) to: empty space
2025/10/28 04:40:49 INFO  : empty space.rclone_chunk.001_txbsq4: Deleted
2025/10/28 04:40:49 DEBUG : potato3.rclone_chunk.001_txbtof: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2025/10/28 04:40:49 INFO  : potato3.rclone_chunk.001_txbtof: Copied (server-side copy) to: potato3.rclone_chunk.001
2025/10/28 04:40:49 INFO  : potato3.rclone_chunk.001_txbtof: Deleted
2025/10/28 04:40:49 DEBUG : potato3.rclone_chunk.002_txbtof: md5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2025/10/28 04:40:49 INFO  : potato3.rclone_chunk.002_txbtof: Copied (server-side copy) to: potato3.rclone_chunk.002
2025/10/28 04:40:49 INFO  : potato3.rclone_chunk.002_txbtof: Deleted
    sync_test.go:1735: Server side move (if possible) Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tunitok6hafe' -> Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-sunebag1loxo'
2025/10/28 04:40:50 INFO  : S3 bucket rclone-test-sunebag1loxo: Bucket "rclone-test-sunebag1loxo" created with ACL ""
2025/10/28 04:40:51 DEBUG : empty space.rclone_chunk.001_txbu29: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2025/10/28 04:40:51 INFO  : empty space.rclone_chunk.001_txbu29: Copied (server-side copy) to: empty space
2025/10/28 04:40:51 INFO  : empty space.rclone_chunk.001_txbu29: Deleted
2025/10/28 04:40:51 DEBUG : potato3.rclone_chunk.001_txbvnp: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2025/10/28 04:40:51 INFO  : potato3.rclone_chunk.001_txbvnp: Copied (server-side copy) to: potato3.rclone_chunk.001
2025/10/28 04:40:52 INFO  : potato3.rclone_chunk.001_txbvnp: Deleted
2025/10/28 04:40:52 DEBUG : potato3.rclone_chunk.002_txbvnp: md5 = 01a6651a94ab602dbfc47587d234b52c OK
2025/10/28 04:40:52 INFO  : potato3.rclone_chunk.002_txbvnp: Copied (server-side copy) to: potato3.rclone_chunk.002
2025/10/28 04:40:52 INFO  : potato3.rclone_chunk.002_txbvnp: Deleted
2025/10/28 04:40:52 DEBUG : empty space: Excluded (Size Filter)
2025/10/28 04:40:52 DEBUG : empty space: Excluded
2025/10/28 04:40:52 DEBUG : empty space: Excluded (Size Filter)
2025/10/28 04:40:52 DEBUG : empty space: Excluded
2025/10/28 04:40:52 DEBUG : potato2: Need to transfer - File not found at Destination
2025/10/28 04:40:52 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2025/10/28 04:40:52 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-sunebag1loxo': Waiting for checks to finish
2025/10/28 04:40:52 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-sunebag1loxo': Waiting for transfers to finish
2025/10/28 04:40:52 DEBUG : potato2: Can't copy - required hash not found
2025/10/28 04:40:52 DEBUG : potato3: Can't copy - required hash not found
2025/10/28 04:40:53 DEBUG : potato2.rclone_chunk.001_txbwsn: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2025/10/28 04:40:53 INFO  : potato2.rclone_chunk.001_txbwsn: Copied (server-side copy) to: potato2.rclone_chunk.001
2025/10/28 04:40:53 INFO  : potato2.rclone_chunk.001_txbwsn: Deleted
2025/10/28 04:40:53 DEBUG : potato2.rclone_chunk.002_txbwsn: md5 = 01a6651a94ab602dbfc47587d234b52c OK
2025/10/28 04:40:53 INFO  : potato2.rclone_chunk.002_txbwsn: Copied (server-side copy) to: potato2.rclone_chunk.002
2025/10/28 04:40:53 DEBUG : potato3.rclone_chunk.001_txbwg2: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2025/10/28 04:40:53 INFO  : potato3.rclone_chunk.001_txbwg2: Copied (server-side copy) to: potato3.rclone_chunk.001
2025/10/28 04:40:53 INFO  : potato2.rclone_chunk.002_txbwsn: Deleted
2025/10/28 04:40:53 INFO  : potato3.rclone_chunk.001_txbwg2: Deleted
2025/10/28 04:40:53 INFO  : potato2: Copied (new)
2025/10/28 04:40:53 DEBUG : potato3.rclone_chunk.002_txbwg2: md5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2025/10/28 04:40:53 INFO  : potato3.rclone_chunk.002_txbwg2: Copied (server-side copy) to: potato3.rclone_chunk.002
2025/10/28 04:40:53 INFO  : potato3.rclone_chunk.002_txbwg2: Deleted
2025/10/28 04:40:53 INFO  : potato3: Copied (replaced existing)
2025/10/28 04:40:53 INFO  : potato2: Deleted
2025/10/28 04:40:53 INFO  : potato3: Deleted
2025/10/28 04:40:54 DEBUG : Creating backend with remote "TestChunkerChunk50bSHA1QuickS3:rclone-test-vovipuj9vojo"
2025/10/28 04:40:54 DEBUG : Creating backend with remote "TestS3:rclone-test-vovipuj9vojo"
2025/10/28 04:40:54 DEBUG : empty space: Excluded (Size Filter)
2025/10/28 04:40:54 DEBUG : empty space: Excluded
2025/10/28 04:40:54 DEBUG : potato2: Need to transfer - File not found at Destination
2025/10/28 04:40:54 DEBUG : potato3: Need to transfer - File not found at Destination
2025/10/28 04:40:54 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-vovipuj9vojo': Waiting for checks to finish
2025/10/28 04:40:54 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-vovipuj9vojo': Waiting for transfers to finish
2025/10/28 04:40:54 DEBUG : potato3: Can't copy - required hash not found
2025/10/28 04:40:54 DEBUG : potato2: Can't copy - required hash not found
2025/10/28 04:40:55 INFO  : S3 bucket rclone-test-vovipuj9vojo: Bucket "rclone-test-vovipuj9vojo" created with ACL ""
2025/10/28 04:40:55 DEBUG : potato2.rclone_chunk.001_txbypd: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2025/10/28 04:40:55 INFO  : potato2.rclone_chunk.001_txbypd: Copied (server-side copy) to: potato2.rclone_chunk.001
2025/10/28 04:40:55 DEBUG : potato3.rclone_chunk.001_txbyc6: md5 = dbbc040ac83a6f6c6816767a36d34c7b OK
2025/10/28 04:40:55 INFO  : potato3.rclone_chunk.001_txbyc6: Copied (server-side copy) to: potato3.rclone_chunk.001
2025/10/28 04:40:55 INFO  : potato2.rclone_chunk.001_txbypd: Deleted
2025/10/28 04:40:55 INFO  : potato3.rclone_chunk.001_txbyc6: Deleted
2025/10/28 04:40:55 DEBUG : potato2.rclone_chunk.002_txbypd: md5 = 01a6651a94ab602dbfc47587d234b52c OK
2025/10/28 04:40:55 INFO  : potato2.rclone_chunk.002_txbypd: Copied (server-side copy) to: potato2.rclone_chunk.002
2025/10/28 04:40:55 INFO  : potato2.rclone_chunk.002_txbypd: Deleted
2025/10/28 04:40:55 DEBUG : potato3.rclone_chunk.002_txbyc6: md5 = 111c387f2584838efb7e0ec6f2fa2e10 OK
2025/10/28 04:40:55 INFO  : potato3.rclone_chunk.002_txbyc6: Copied (server-side copy) to: potato3.rclone_chunk.002
2025/10/28 04:40:55 INFO  : potato2: Copied (new)
2025/10/28 04:40:55 INFO  : potato3.rclone_chunk.002_txbyc6: Deleted
2025/10/28 04:40:55 INFO  : potato3: Copied (new)
2025/10/28 04:40:55 INFO  : potato2: Deleted
2025/10/28 04:40:56 INFO  : potato3: Deleted
2025/10/28 04:40:56 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-vovipuj9vojo': Purge remote
2025/10/28 04:40:56 DEBUG : S3 bucket rclone-test-vovipuj9vojo: bucket is versioned: false
2025/10/28 04:40:56 DEBUG : Waiting for deletions to finish
2025/10/28 04:40:56 DEBUG : "potato2" version false
2025/10/28 04:40:56 DEBUG : potato2: Deleting (id "")
2025/10/28 04:40:56 DEBUG : "potato2.rclone_chunk.001" version false
2025/10/28 04:40:56 DEBUG : potato2.rclone_chunk.001: Deleting (id "")
2025/10/28 04:40:56 DEBUG : "potato2.rclone_chunk.002" version false
2025/10/28 04:40:56 DEBUG : potato2.rclone_chunk.002: Deleting (id "")
2025/10/28 04:40:56 DEBUG : "potato3" version false
2025/10/28 04:40:56 DEBUG : potato3: Deleting (id "")
2025/10/28 04:40:56 DEBUG : "potato3.rclone_chunk.001" version false
2025/10/28 04:40:56 DEBUG : potato3.rclone_chunk.001: Deleting (id "")
2025/10/28 04:40:56 DEBUG : "potato3.rclone_chunk.002" version false
2025/10/28 04:40:56 DEBUG : potato3.rclone_chunk.002: Deleting (id "")
2025/10/28 04:40:56 INFO  : potato2.rclone_chunk.001: Deleted
2025/10/28 04:40:56 INFO  : potato2: Deleted
2025/10/28 04:40:56 INFO  : potato2.rclone_chunk.002: Deleted
2025/10/28 04:40:56 INFO  : potato3: Deleted
2025/10/28 04:40:56 INFO  : potato3.rclone_chunk.002: Deleted
2025/10/28 04:40:56 INFO  : potato3.rclone_chunk.001: Deleted
2025/10/28 04:40:56 INFO  : S3 bucket rclone-test-vovipuj9vojo: Bucket "rclone-test-vovipuj9vojo" deleted
2025/10/28 04:40:56 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-sunebag1loxo': Purge remote
2025/10/28 04:40:56 DEBUG : S3 bucket rclone-test-sunebag1loxo: bucket is versioned: false
2025/10/28 04:40:56 DEBUG : Waiting for deletions to finish
2025/10/28 04:40:56 DEBUG : "empty space" version false
2025/10/28 04:40:56 DEBUG : empty space: Deleting (id "")
2025/10/28 04:40:56 INFO  : empty space: Deleted
2025/10/28 04:40:57 INFO  : S3 bucket rclone-test-sunebag1loxo: Bucket "rclone-test-sunebag1loxo" deleted
--- PASS: TestServerSideMoveWithFilter (10.19s)
PASS
2025/10/28 04:40:57 DEBUG : Chunked 'TestChunkerChunk50bSHA1QuickS3:rclone-test-tunitok6hafe': Purge remote
2025/10/28 04:40:57 DEBUG : S3 bucket rclone-test-tunitok6hafe: bucket is versioned: false
2025/10/28 04:40:57 DEBUG : Waiting for deletions to finish
2025/10/28 04:40:58 INFO  : S3 bucket rclone-test-tunitok6hafe: Bucket "rclone-test-tunitok6hafe" deleted
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bSHA1QuickS3: -verbose -size-limit 1024 -test.run '^TestServerSideMoveWithFilter$'" - Finished OK in 10.883348873s (try 2/5)