"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^(TestCheck|TestCheckDownload|TestCopyURL|TestDeduplicateOldest|TestDirMove|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestRcatSize|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsWithFilter|TestSetDirModTime|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Starting (try 2/5)
2025/04/10 02:51:10 DEBUG : Creating backend with remote "TestGoFile:rclone-test-qelepip2poxi"
2025/04/10 02:51:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/04/10 02:51:10 DEBUG : Creating backend with remote "/tmp/rclone2182204437"
=== RUN   TestCheck
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:51:11 DEBUG : Upload server store6 (eu) responded in 131.808639ms
2025/04/10 02:51:11 DEBUG : Upload server store-eu-par-2 (eu) responded in 129.765235ms
2025/04/10 02:51:11 DEBUG : rutabaga: Using upload server store-eu-par-2 (eu)
=== RUN   TestCheck/1
=== RUN   TestCheck/2
2025/04/10 02:51:11 DEBUG : empty space: Using upload server store6 (eu)
=== RUN   TestCheck/3
2025/04/10 02:51:12 DEBUG : potato2: Using upload server store6 (eu)
=== RUN   TestCheck/4
=== RUN   TestCheck/5
2025/04/10 02:51:12 DEBUG : remotepotato: Using upload server store6 (eu)
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (3.51s)
    --- PASS: TestCheck/1 (0.07s)
    --- PASS: TestCheck/2 (0.09s)
    --- PASS: TestCheck/3 (0.07s)
    --- PASS: TestCheck/4 (0.10s)
    --- PASS: TestCheck/5 (0.09s)
    --- PASS: TestCheck/6 (0.10s)
    --- PASS: TestCheck/7 (0.10s)
=== RUN   TestCheckDownload
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:51:14 DEBUG : rutabaga: Using upload server store6 (eu)
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2025/04/10 02:51:14 DEBUG : empty space: Using upload server store6 (eu)
=== RUN   TestCheckDownload/3
2025/04/10 02:51:15 DEBUG : potato2: Using upload server store-eu-par-2 (eu)
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2025/04/10 02:51:16 DEBUG : remotepotato: Using upload server store-eu-par-2 (eu)
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (3.43s)
    --- PASS: TestCheckDownload/1 (0.15s)
    --- PASS: TestCheckDownload/2 (0.15s)
    --- PASS: TestCheckDownload/3 (0.12s)
    --- PASS: TestCheckDownload/4 (0.13s)
    --- PASS: TestCheckDownload/5 (0.16s)
    --- PASS: TestCheckDownload/6 (0.14s)
    --- PASS: TestCheckDownload/7 (0.13s)
=== RUN   TestDeduplicateOldest
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:51:17 DEBUG : one: Using upload server store6 (eu)
2025/04/10 02:51:17 DEBUG : one: Using upload server store-eu-par-2 (eu)
2025/04/10 02:51:17 DEBUG : one: Using upload server store-eu-par-2 (eu)
2025/04/10 02:51:18 INFO  : gofile root 'rclone-test-qelepip2poxi': Looking for duplicate names using oldest mode.
2025/04/10 02:51:18 NOTICE: one: Found 3 files with duplicate names
2025/04/10 02:51:18 INFO  : one: Deleted
2025/04/10 02:51:18 INFO  : one: Deleted
2025/04/10 02:51:18 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateOldest (1.73s)
=== RUN   TestRmdirsLeaveRoot
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:51:19 INFO  : A1: Making directory
2025/04/10 02:51:19 INFO  : A1/B1: Making directory
2025/04/10 02:51:20 INFO  : A1/B1/C1: Making directory
2025/04/10 02:51:21 DEBUG : removing 1 level 3 directories
2025/04/10 02:51:21 INFO  : A1/B1/C1: Removing directory
2025/04/10 02:51:21 DEBUG : removing 1 level 2 directories
2025/04/10 02:51:21 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (2.92s)
=== RUN   TestRmdirsWithFilter
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:51:22 INFO  : A1: Making directory
2025/04/10 02:51:22 INFO  : A1/B1: Making directory
2025/04/10 02:51:23 INFO  : A1/B1/C1: Making directory
2025/04/10 02:51:24 DEBUG : removing 1 level 3 directories
2025/04/10 02:51:24 INFO  : A1/B1/C1: Removing directory
2025/04/10 02:51:24 DEBUG : removing 1 level 2 directories
2025/04/10 02:51:24 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (2.98s)
=== RUN   TestCopyURL
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:51:25 DEBUG : file1: Using upload server store-eu-par-2 (eu)
2025/04/10 02:51:25 DEBUG : filename.txt: File name found in url
2025/04/10 02:51:25 DEBUG : filename.txt: Using upload server store-eu-par-2 (eu)
2025/04/10 02:51:26 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2025/04/10 02:51:26 DEBUG : headerfilename.txt: Using upload server store-eu-par-2 (eu)
2025/04/10 02:51:26 DEBUG : file2: Using upload server store6 (eu)
--- PASS: TestCopyURL (2.45s)
=== RUN   TestMoveFile
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:51:27 DEBUG : file1: Need to transfer - File not found at Destination
2025/04/10 02:51:28 DEBUG : sub/file2: Using upload server store6 (eu)
2025/04/10 02:51:28 DEBUG : file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2025/04/10 02:51:28 INFO  : file1: Copied (new) to: sub/file2
2025/04/10 02:51:28 INFO  : file1: Deleted
2025/04/10 02:51:28 DEBUG : file1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/04/10 02:51:28 DEBUG : file1: Unchanged skipping
2025/04/10 02:51:28 INFO  : file1: Deleted
2025/04/10 02:51:28 DEBUG : gofile root 'rclone-test-qelepip2poxi': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (1.85s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:51:29 DEBUG : file1: Need to transfer - File not found at Destination
2025/04/10 02:51:29 DEBUG : file1: Using upload server store6 (eu)
2025/04/10 02:51:29 DEBUG : file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2025/04/10 02:51:29 INFO  : file1: Copied (new)
2025/04/10 02:51:29 INFO  : file1: Deleted
2025/04/10 02:51:30 DEBUG : file1: Destination exists, skipping
2025/04/10 02:51:30 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (1.10s)
=== RUN   TestMoveFileBackupDir
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:51:31 DEBUG : dst/file1: Using upload server store6 (eu)
2025/04/10 02:51:31 DEBUG : Creating backend with remote "TestGoFile:rclone-test-qelepip2poxi/backup"
2025/04/10 02:51:32 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18)
2025/04/10 02:51:33 INFO  : dst/file1: Moved (server-side)
2025/04/10 02:51:33 DEBUG : dst/file1: Using upload server store-eu-par-2 (eu)
2025/04/10 02:51:33 DEBUG : dst/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2025/04/10 02:51:33 INFO  : dst/file1: Copied (new)
2025/04/10 02:51:33 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (5.48s)
=== RUN   TestDirMove
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:51:36 DEBUG : A1/one: Using upload server store6 (eu)
2025/04/10 02:51:36 DEBUG : A1/two: Using upload server store6 (eu)
2025/04/10 02:51:37 DEBUG : A1/B1/three: Using upload server store-eu-par-2 (eu)
2025/04/10 02:51:38 DEBUG : A1/B1/C1/four: Using upload server store-eu-par-2 (eu)
2025/04/10 02:51:38 DEBUG : A1/B1/C2/five: Using upload server store-eu-par-2 (eu)
2025/04/10 02:51:39 INFO  : A1/B2: Making directory
2025/04/10 02:51:39 INFO  : A1/B1/C3: Making directory
2025/04/10 02:51:42 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:51:47 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/04/10 02:51:47 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/04/10 02:51:47 DEBUG : pacer: Reducing sleep to 10ms
2025/04/10 02:51:47 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:51:52 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/04/10 02:51:52 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/04/10 02:51:53 DEBUG : pacer: Reducing sleep to 10ms
2025/04/10 02:51:53 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:51:53 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:51:53 INFO  : A2/one: Moved (server-side) to: A3/one
2025/04/10 02:51:53 INFO  : A2/two: Moved (server-side) to: A3/two
2025/04/10 02:51:53 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2025/04/10 02:51:58 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/04/10 02:51:58 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/04/10 02:51:58 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/04/10 02:51:58 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/04/10 02:51:58 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:51:58 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:03 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/04/10 02:52:03 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/04/10 02:52:03 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/04/10 02:52:03 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/04/10 02:52:03 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:03 DEBUG : pacer: Reducing sleep to 80ms
2025/04/10 02:52:03 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2025/04/10 02:52:08 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/04/10 02:52:08 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/04/10 02:52:08 DEBUG : pacer: Reducing sleep to 80ms
2025/04/10 02:52:08 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2025/04/10 02:52:09 DEBUG : pacer: Reducing sleep to 40ms
2025/04/10 02:52:09 DEBUG : pacer: Reducing sleep to 20ms
2025/04/10 02:52:09 DEBUG : pacer: Reducing sleep to 10ms
2025/04/10 02:52:11 INFO  : gofile root 'rclone-test-qelepip2poxi': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2025/04/10 02:52:14 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:14 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:14 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:14 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2025/04/10 02:52:14 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2025/04/10 02:52:19 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/04/10 02:52:19 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/04/10 02:52:19 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/04/10 02:52:19 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/04/10 02:52:19 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/04/10 02:52:19 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/04/10 02:52:19 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:19 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:19 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:24 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/04/10 02:52:24 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/04/10 02:52:24 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/04/10 02:52:24 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2025/04/10 02:52:24 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:24 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/04/10 02:52:24 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2025/04/10 02:52:24 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:24 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:29 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/04/10 02:52:29 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2025/04/10 02:52:29 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:29 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/04/10 02:52:29 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2025/04/10 02:52:29 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/04/10 02:52:29 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2025/04/10 02:52:30 DEBUG : Rate limited, sleep for 5s
2025/04/10 02:52:34 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/04/10 02:52:34 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2025/04/10 02:52:35 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/04/10 02:52:35 DEBUG : pacer: Rate limited, increasing sleep to 20s
2025/04/10 02:52:36 DEBUG : pacer: Reducing sleep to 10s
2025/04/10 02:52:36 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2025/04/10 02:52:56 DEBUG : pacer: Reducing sleep to 5s
2025/04/10 02:52:56 INFO  : A3/two: Moved (server-side) to: A4/two
2025/04/10 02:53:06 DEBUG : pacer: Reducing sleep to 2.5s
2025/04/10 02:53:06 INFO  : A3/one: Moved (server-side) to: A4/one
2025/04/10 02:53:10 DEBUG : pacer: Reducing sleep to 1.25s
2025/04/10 02:53:13 DEBUG : pacer: Reducing sleep to 625ms
2025/04/10 02:53:14 DEBUG : pacer: Reducing sleep to 312.5ms
2025/04/10 02:53:15 DEBUG : pacer: Reducing sleep to 156.25ms
2025/04/10 02:53:15 DEBUG : pacer: Reducing sleep to 78.125ms
2025/04/10 02:53:15 DEBUG : pacer: Reducing sleep to 39.0625ms
2025/04/10 02:53:15 DEBUG : pacer: Reducing sleep to 19.53125ms
2025/04/10 02:53:15 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestDirMove (103.63s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:53:19 DEBUG : gofile root 'rclone-test-qelepip2poxi': File to upload is small (34 bytes), uploading instead of streaming
2025/04/10 02:53:20 DEBUG : Upload server store-eu-par-2 (eu) responded in 129.598957ms
2025/04/10 02:53:20 DEBUG : Upload server store7 (eu) responded in 135.164328ms
2025/04/10 02:53:20 DEBUG : no_checksum_small_file_from_pipe: Using upload server store-eu-par-2 (eu)
2025/04/10 02:53:20 DEBUG : no_checksum_small_file_from_pipe: md5 = 4c762bc6ec18f21b23fcae426c7863b5 OK
2025/04/10 02:53:20 DEBUG : no_checksum_small_file_from_pipe: Size and md5 of src and dst objects identical
2025/04/10 02:53:20 DEBUG : no_checksum_big_file_from_pipe: Using upload server store7 (eu)
2025/04/10 02:53:20 DEBUG : no_checksum_big_file_from_pipe: md5 = fffc7956ba9a7b58a63c01b6ce1ddc45 OK
2025/04/10 02:53:20 DEBUG : no_checksum_big_file_from_pipe: Size and md5 of src and dst objects identical
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=false
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:53:21 DEBUG : gofile root 'rclone-test-qelepip2poxi': File to upload is small (34 bytes), uploading instead of streaming
2025/04/10 02:53:21 DEBUG : with_checksum_small_file_from_pipe: Using upload server store-eu-par-2 (eu)
2025/04/10 02:53:21 DEBUG : with_checksum_small_file_from_pipe: md5 = 4c762bc6ec18f21b23fcae426c7863b5 OK
2025/04/10 02:53:21 DEBUG : with_checksum_small_file_from_pipe: Size and md5 of src and dst objects identical
2025/04/10 02:53:21 DEBUG : with_checksum_big_file_from_pipe: Using upload server store7 (eu)
2025/04/10 02:53:22 DEBUG : with_checksum_big_file_from_pipe: md5 = fffc7956ba9a7b58a63c01b6ce1ddc45 OK
2025/04/10 02:53:22 DEBUG : with_checksum_big_file_from_pipe: Size and md5 of src and dst objects identical
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=true
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:53:22 DEBUG : gofile root 'rclone-test-qelepip2poxi': File to upload is small (34 bytes), uploading instead of streaming
2025/04/10 02:53:23 DEBUG : ignore_checksum_small_file_from_pipe: Using upload server store7 (eu)
2025/04/10 02:53:23 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/04/10 02:53:23 DEBUG : ignore_checksum_big_file_from_pipe: Using upload server store-eu-par-2 (eu)
2025/04/10 02:53:23 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=true
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:53:24 DEBUG : gofile root 'rclone-test-qelepip2poxi': File to upload is small (34 bytes), uploading instead of streaming
2025/04/10 02:53:24 DEBUG : ignore_checksum_small_file_from_pipe: Using upload server store-eu-par-2 (eu)
2025/04/10 02:53:24 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check
2025/04/10 02:53:24 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2025/04/10 02:53:24 DEBUG : ignore_checksum_big_file_from_pipe: Using upload server store7 (eu)
2025/04/10 02:53:25 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check
2025/04/10 02:53:25 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (6.27s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (1.57s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (1.54s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (1.72s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (1.44s)
=== RUN   TestRcatSize
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:53:26 DEBUG : potato1: Using upload server store-eu-par-2 (eu)
2025/04/10 02:53:26 DEBUG : gofile root 'rclone-test-qelepip2poxi': File to upload is small (60 bytes), uploading instead of streaming
2025/04/10 02:53:26 DEBUG : potato2: Using upload server store-eu-par-2 (eu)
2025/04/10 02:53:26 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/04/10 02:53:26 DEBUG : potato2: Size and md5 of src and dst objects identical
--- PASS: TestRcatSize (1.31s)
=== RUN   TestTouchDir
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:53:27 DEBUG : potato2: Using upload server store-eu-par-2 (eu)
2025/04/10 02:53:27 DEBUG : empty space: Using upload server store7 (eu)
2025/04/10 02:53:28 DEBUG : sub dir/potato3: Using upload server store7 (eu)
2025/04/10 02:53:28 DEBUG : gofile root 'rclone-test-qelepip2poxi': Touching "potato2"
2025/04/10 02:53:28 DEBUG : gofile root 'rclone-test-qelepip2poxi': Touching "empty space"
2025/04/10 02:53:28 DEBUG : gofile root 'rclone-test-qelepip2poxi': Touching "sub dir/potato3"
--- PASS: TestTouchDir (3.01s)
=== RUN   TestMkdirModTime
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:53:30 INFO  : directory with modtime: Making directory
2025/04/10 02:53:31 INFO  : directory with modtime: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
--- PASS: TestMkdirModTime (1.25s)
=== RUN   TestSetDirModTime
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:53:31 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set
2025/04/10 02:53:32 INFO  : set modtime on existing directory: Set directory modification time (using SetModTime)
2025/04/10 02:53:32 INFO  : set modtime on existing directory: Set directory modification time (using DirSetModTime)
--- PASS: TestSetDirModTime (1.81s)
=== RUN   TestRemoveExisting
    run.go:180: Remote "gofile root 'rclone-test-qelepip2poxi'", Local "Local file system at /tmp/rclone2182204437", Modify Window "1s"
2025/04/10 02:53:33 DEBUG : sub dir/test remove existing: Using upload server store-eu-par-2 (eu)
2025/04/10 02:53:34 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: Using upload server store-eu-par-2 (eu)
2025/04/10 02:53:34 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.cogiper7" before starting
2025/04/10 02:53:35 DEBUG : sub dir/test remove existing.cogiper7: TEST: removing renamed existing file after operation
2025/04/10 02:53:35 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.saruguh7" before starting
2025/04/10 02:53:35 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.saruguh7: TEST: renaming existing back after failed operation
2025/04/10 02:53:36 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.fedusan7" before starting
2025/04/10 02:53:36 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.fedusan7: TEST: removing renamed existing file after operation
--- PASS: TestRemoveExisting (4.54s)
PASS
2025/04/10 02:53:37 DEBUG : gofile root 'rclone-test-qelepip2poxi': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^(TestCheck|TestCheckDownload|TestCopyURL|TestDeduplicateOldest|TestDirMove|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestRcatSize|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsWithFilter|TestSetDirModTime|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Finished OK in 2m27.68426849s (try 2/5)