"./operations.test -test.v -test.timeout 1h0m0s -remote TestS3R2: -verbose -fast-list -test.run '^(TestCopyURL|TestMoveFile|TestMoveFileBackupDir|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=true)$'" - Starting (try 2/5)
2025/06/19 01:45:06 DEBUG : Creating backend with remote "TestS3R2:rclone-test-goyatik3cowe"
2025/06/19 01:45:06 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/06/19 01:45:06 DEBUG : Creating backend with remote "/tmp/rclone1332530865"
=== RUN   TestCopyURL
    run.go:180: Remote "S3 bucket rclone-test-goyatik3cowe", Local "Local file system at /tmp/rclone1332530865", Modify Window "1ns"
2025/06/19 01:45:07 INFO  : S3 bucket rclone-test-goyatik3cowe: Bucket "rclone-test-goyatik3cowe" created with ACL ""
2025/06/19 01:45:07 DEBUG : filename.txt: File name found in url
2025/06/19 01:45:08 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
--- PASS: TestCopyURL (3.47s)
=== RUN   TestMoveFile
    run.go:180: Remote "S3 bucket rclone-test-goyatik3cowe", Local "Local file system at /tmp/rclone1332530865", Modify Window "1ns"
2025/06/19 01:45:09 DEBUG : file1: Need to transfer - File not found at Destination
2025/06/19 01:45:10 DEBUG : file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2025/06/19 01:45:10 INFO  : file1: Copied (new) to: sub/file2
2025/06/19 01:45:10 INFO  : file1: Deleted
2025/06/19 01:45:10 DEBUG : file1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/06/19 01:45:10 DEBUG : file1: Unchanged skipping
2025/06/19 01:45:10 INFO  : file1: Deleted
2025/06/19 01:45:10 DEBUG : S3 bucket rclone-test-goyatik3cowe: don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (1.21s)
=== RUN   TestMoveFileBackupDir
    run.go:180: Remote "S3 bucket rclone-test-goyatik3cowe", Local "Local file system at /tmp/rclone1332530865", Modify Window "1ns"
2025/06/19 01:45:11 DEBUG : Creating backend with remote "TestS3R2:rclone-test-goyatik3cowe/backup"
2025/06/19 01:45:11 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18)
2025/06/19 01:45:12 DEBUG : dst/file1: md5 = 05164b153084ba910184c26e561a7c18 OK
2025/06/19 01:45:12 INFO  : dst/file1: Copied (server-side copy)
2025/06/19 01:45:12 INFO  : dst/file1: Deleted
2025/06/19 01:45:12 DEBUG : dst/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2025/06/19 01:45:12 INFO  : dst/file1: Copied (new)
2025/06/19 01:45:12 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (2.55s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:180: Remote "S3 bucket rclone-test-goyatik3cowe", Local "Local file system at /tmp/rclone1332530865", Modify Window "1ns"
2025/06/19 01:45:13 DEBUG : S3 bucket rclone-test-goyatik3cowe: File to upload is small (34 bytes), uploading instead of streaming
2025/06/19 01:45:13 DEBUG : no_checksum_small_file_from_pipe: md5 = 4c762bc6ec18f21b23fcae426c7863b5 OK
2025/06/19 01:45:13 DEBUG : no_checksum_small_file_from_pipe: Size and md5 of src and dst objects identical
2025/06/19 01:45:13 NOTICE: S3 bucket rclone-test-goyatik3cowe: Streaming uploads using chunk size 5Mi will have maximum file size of 48.828Gi
2025/06/19 01:45:14 DEBUG : no_checksum_big_file_from_pipe: open chunk writer: started multipart upload: AMz6jaCfnBEt2U19CjTIkiZVK8DH9zziAsGQ2FdPaUFGhDVTwKiAR5DBqqMY_XvQ4-j8sSbfx9mKZ5Wx1jmyuQDiRLRV-qGHOkziIcYAInI_1Nftm1CSMztbELkkPdcMRE8j8K06dunxQRmnfhWP1a3NzEdcpai7tbZ0jTp2eJeOc5jTZt03h2xF_Y1zT-E4KiIEcwxznW17l3Ap9jKpIBLtNeZ8eW3L2hr9vYYHWzzURnRYQaqfIuUIy10dd1h3CCbbItXjkUtvatKeZIllWa4fc0qtEzrcCaRttsJ5rMnM3H56qR09s-t9-dbVvWxRXvh6RcfYxkuz5tpQYXn9nC0
2025/06/19 01:45:14 DEBUG : no_checksum_big_file_from_pipe: multipart upload: starting chunk 0 size 100.001Ki offset 0/off
2025/06/19 01:45:14 DEBUG : no_checksum_big_file_from_pipe: multipart upload wrote chunk 1 with 102401 bytes and etag "fffc7956ba9a7b58a63c01b6ce1ddc45"
2025/06/19 01:45:14 DEBUG : no_checksum_big_file_from_pipe: multipart upload "AMz6jaCfnBEt2U19CjTIkiZVK8DH9zziAsGQ2FdPaUFGhDVTwKiAR5DBqqMY_XvQ4-j8sSbfx9mKZ5Wx1jmyuQDiRLRV-qGHOkziIcYAInI_1Nftm1CSMztbELkkPdcMRE8j8K06dunxQRmnfhWP1a3NzEdcpai7tbZ0jTp2eJeOc5jTZt03h2xF_Y1zT-E4KiIEcwxznW17l3Ap9jKpIBLtNeZ8eW3L2hr9vYYHWzzURnRYQaqfIuUIy10dd1h3CCbbItXjkUtvatKeZIllWa4fc0qtEzrcCaRttsJ5rMnM3H56qR09s-t9-dbVvWxRXvh6RcfYxkuz5tpQYXn9nC0" finished
2025/06/19 01:45:14 DEBUG : no_checksum_big_file_from_pipe: Dst hash empty - aborting Src hash check
2025/06/19 01:45:14 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=true
    run.go:180: Remote "S3 bucket rclone-test-goyatik3cowe", Local "Local file system at /tmp/rclone1332530865", Modify Window "1ns"
2025/06/19 01:45:15 DEBUG : S3 bucket rclone-test-goyatik3cowe: File to upload is small (34 bytes), uploading instead of streaming
2025/06/19 01:45:15 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/06/19 01:45:15 DEBUG : ignore_checksum_big_file_from_pipe: open chunk writer: started multipart upload: AIoz69aOOw-iEYe86h6jGJNFNeoMxUlmtIoIkKmLuemdjx9GDWy4qoq6Chzm0ZxH493iO_KuSpyXp_3EwbaXLDSum4NCwr_P3X5eAhHlvSeieHOJfqrlg5yJVO5f97tGJr3vHA731v9P0UDoAg65e8oGOZ-eQRpqz_1DUOo2cy_WZRgm5PrtRAI794MA0DJFgGjRrcQuP6AODa_hmjgwOgC5tm3wmLgZKUWTvtV71KpxDyxyt1INRnGeW517UN6OatemRNMVW3GEAAK5cEitcqE3tcTd87Ugi94vNJw7nZ8sUtCvjYx-Ql6C7kp7EgjjZ5zOrHehcXKiaLVyn1bg66w
2025/06/19 01:45:15 DEBUG : ignore_checksum_big_file_from_pipe: multipart upload: starting chunk 0 size 100.001Ki offset 0/off
2025/06/19 01:45:16 DEBUG : ignore_checksum_big_file_from_pipe: multipart upload wrote chunk 1 with 102401 bytes and etag "fffc7956ba9a7b58a63c01b6ce1ddc45"
2025/06/19 01:45:16 DEBUG : ignore_checksum_big_file_from_pipe: multipart upload "AIoz69aOOw-iEYe86h6jGJNFNeoMxUlmtIoIkKmLuemdjx9GDWy4qoq6Chzm0ZxH493iO_KuSpyXp_3EwbaXLDSum4NCwr_P3X5eAhHlvSeieHOJfqrlg5yJVO5f97tGJr3vHA731v9P0UDoAg65e8oGOZ-eQRpqz_1DUOo2cy_WZRgm5PrtRAI794MA0DJFgGjRrcQuP6AODa_hmjgwOgC5tm3wmLgZKUWTvtV71KpxDyxyt1INRnGeW517UN6OatemRNMVW3GEAAK5cEitcqE3tcTd87Ugi94vNJw7nZ8sUtCvjYx-Ql6C7kp7EgjjZ5zOrHehcXKiaLVyn1bg66w" finished
2025/06/19 01:45:16 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by 0s, within tolerance 1ns)
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=true
    run.go:180: Remote "S3 bucket rclone-test-goyatik3cowe", Local "Local file system at /tmp/rclone1332530865", Modify Window "1ns"
2025/06/19 01:45:17 DEBUG : S3 bucket rclone-test-goyatik3cowe: File to upload is small (34 bytes), uploading instead of streaming
2025/06/19 01:45:17 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check
2025/06/19 01:45:17 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2025/06/19 01:45:17 DEBUG : ignore_checksum_big_file_from_pipe: open chunk writer: started multipart upload: APmThESB6Ek3Wz4TEkdJGsznnos2mII5Q48wL1Usp3EmtBHXjWXLz9PEVt1kVsBpghmk9Lv9e2M8s8lnKPpKyyOMS09fwQRHkt-XrMiZ_N2ZY6njXj1pEPCx65wzYoxY-q0UTndVDRJXgcjB0Y9lWW23vg-3IuVzcxMomg56S7WMDK8sY_nEOgbW-SUIPjbJL-gUUQfYQOYoK1NZxg-aU0Rhz7zLUgXF_eS8IiK4OlwnVgEtpvOD4CHDH8k7jmWF4CIEGquhtD_Wa7hBElEMuJyGuf0uBvPzHdL3feml2Nb17jGK_m6VtANBRk2TsSRguRAlxXy-3l-5FIbT_comEgM
2025/06/19 01:45:17 DEBUG : ignore_checksum_big_file_from_pipe: multipart upload: starting chunk 0 size 100.001Ki offset 0/off
2025/06/19 01:45:17 DEBUG : ignore_checksum_big_file_from_pipe: multipart upload wrote chunk 1 with 102401 bytes and etag "fffc7956ba9a7b58a63c01b6ce1ddc45"
2025/06/19 01:45:18 DEBUG : ignore_checksum_big_file_from_pipe: multipart upload "APmThESB6Ek3Wz4TEkdJGsznnos2mII5Q48wL1Usp3EmtBHXjWXLz9PEVt1kVsBpghmk9Lv9e2M8s8lnKPpKyyOMS09fwQRHkt-XrMiZ_N2ZY6njXj1pEPCx65wzYoxY-q0UTndVDRJXgcjB0Y9lWW23vg-3IuVzcxMomg56S7WMDK8sY_nEOgbW-SUIPjbJL-gUUQfYQOYoK1NZxg-aU0Rhz7zLUgXF_eS8IiK4OlwnVgEtpvOD4CHDH8k7jmWF4CIEGquhtD_Wa7hBElEMuJyGuf0uBvPzHdL3feml2Nb17jGK_m6VtANBRk2TsSRguRAlxXy-3l-5FIbT_comEgM" finished
2025/06/19 01:45:18 DEBUG : ignore_checksum_big_file_from_pipe: Dst hash empty - aborting Src hash check
2025/06/19 01:45:18 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check
2025/06/19 01:45:18 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (5.23s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (1.73s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (1.66s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (1.84s)
=== RUN   TestTouchDir
    run.go:180: Remote "S3 bucket rclone-test-goyatik3cowe", Local "Local file system at /tmp/rclone1332530865", Modify Window "1ns"
2025/06/19 01:45:20 DEBUG : S3 bucket rclone-test-goyatik3cowe: Touching "sub dir/potato3"
2025/06/19 01:45:20 DEBUG : S3 bucket rclone-test-goyatik3cowe: Touching "empty space"
2025/06/19 01:45:20 DEBUG : S3 bucket rclone-test-goyatik3cowe: Touching "potato2"
--- PASS: TestTouchDir (2.69s)
PASS
2025/06/19 01:45:21 DEBUG : S3 bucket rclone-test-goyatik3cowe: Purge remote
2025/06/19 01:45:21 DEBUG : S3 bucket rclone-test-goyatik3cowe: bucket is versioned: false
2025/06/19 01:45:21 DEBUG : Waiting for deletions to finish
2025/06/19 01:45:22 INFO  : S3 bucket rclone-test-goyatik3cowe: Bucket "rclone-test-goyatik3cowe" deleted
"./operations.test -test.v -test.timeout 1h0m0s -remote TestS3R2: -verbose -fast-list -test.run '^(TestCopyURL|TestMoveFile|TestMoveFileBackupDir|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=true)$'" - Finished OK in 16.129522827s (try 2/5)