"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestBase64|TestManualTransformFile|TestTransformFile)$'" - Starting (try 4/5)
2025/09/05 03:56:45 DEBUG : Creating backend with remote "TestDropbox:rclone-test-ramebor2yuca"
2025/09/05 03:56:45 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/09/05 03:56:46 DEBUG : Creating backend with remote "/tmp/rclone953504361"
=== RUN   TestTransformFile
    run.go:180: Remote "Dropbox root 'rclone-test-ramebor2yuca'", Local "Local file system at /tmp/rclone953504361", Modify Window "1s"
2025/09/05 03:56:46 DEBUG : empty_dir: Making directory with metadata
2025/09/05 03:56:46 INFO  : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2025/09/05 03:56:47 DEBUG : toe: transformed to: tictactoe
2025/09/05 03:56:47 DEBUG : empty_dir: transformed to: tictacempty_dir
2025/09/05 03:56:47 DEBUG : empty_dir: transformed to: tictacempty_dir
2025/09/05 03:56:47 DEBUG : empty_dir: transformed to: tictacempty_dir
2025/09/05 03:56:47 INFO  : tictacempty_dir: Making directory
2025/09/05 03:56:48 DEBUG : empty_dir: transformed to: tictacempty_dir
2025/09/05 03:56:48 DEBUG : toe: transformed to: tictactoe
2025/09/05 03:56:48 DEBUG : toe: transformed to: tictactoe
2025/09/05 03:56:48 INFO  : tictactoe: Making directory
2025/09/05 03:56:48 DEBUG : toe: transformed to: tictactoe
2025/09/05 03:56:48 DEBUG : toe: transformed to: tictactoe
2025/09/05 03:56:48 DEBUG : toe/toe: transformed to: tictactoe/tictactoe
2025/09/05 03:56:48 INFO  : tictactoe/tictactoe: Making directory
2025/09/05 03:56:49 DEBUG : toe/toe: transformed to: tictactoe/tictactoe
2025/09/05 03:56:49 DEBUG : toe.txt: transformed to: tictactoe.txt
2025/09/05 03:56:49 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt
2025/09/05 03:56:49 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination
2025/09/05 03:56:49 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt
2025/09/05 03:56:49 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt
2025/09/05 03:56:49 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Waiting for checks to finish
2025/09/05 03:56:49 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Waiting for transfers to finish
2025/09/05 03:56:50 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 1/1
2025/09/05 03:56:50 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 2/1
2025/09/05 03:56:51 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Adding "tictactoe/tictactoe/tictactoe.txt" to batch
2025/09/05 03:56:52 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Batch idle for 500ms so committing
2025/09/05 03:56:52 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Committing sync batch length 1 starting with: tictactoe/tictactoe/tictactoe.txt
2025/09/05 03:56:52 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Committed sync batch length 1 starting with: tictactoe/tictactoe/tictactoe.txt
2025/09/05 03:56:52 DEBUG : toe/toe/toe.txt: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2025/09/05 03:56:52 INFO  : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt
2025/09/05 03:56:52 INFO  : toe/toe/toe.txt: Deleted
2025/09/05 03:56:52 INFO  : toe/toe: Removing directory
2025/09/05 03:56:52 INFO  : toe: Removing directory
2025/09/05 03:56:52 INFO  : empty_dir: Removing directory
2025/09/05 03:56:52 DEBUG : Local file system at /tmp/rclone953504361: deleted 3 directories
2025/09/05 03:56:54 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s)
2025/09/05 03:56:54 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt
2025/09/05 03:56:54 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt
2025/09/05 03:56:54 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt
2025/09/05 03:56:55 INFO  : tictactoe/tictactoe/tictactoe.txt: Moved (server-side) to: toe/toe/toe.txt
    run.go:125: removing dir "toe/toe" failed - try 1/3: Rmdir: <!DOCTYPE html>
        <html>
        <head><meta http-equiv="Content-Type" content="text/html; charset=utf-8">
        <meta name="viewport" content="width=device-width, initial-scale=1" />
        <title>Dropbox - 504</title>
        <link href="https://cfl.dropboxstatic.com/static/metaserver/static/css/error.css" rel="stylesheet" type="text/css"/>
        <link rel="shortcut icon" href="https://cfl.dropboxstatic.com/static/images/favicon.ico"/>
        </head>
        <body>
        <div class="figure">
        <img src="https://assets.dropbox.com/www/en-us/illustrations/spot/target-miss.svg" alt="Error: 504"/>
        </div>
        <div id="errorbox">
        <h1>Error (504)</h1>Something went wrong. Don't worry, your files are still safe and the Dropbox team has been notified. Check out our <a href="https://status.dropbox.com">Status Page</a> to see if there is a known incident, our <a href="https://www.dropbox.com/help">Help Center</a> and <a href="https://forums.dropbox.com">forums</a> for help, or head back to <a href="https://www.dropbox.com/home">home</a>.
        </div>
        
        </body>
        </html>
--- PASS: TestTransformFile (109.16s)
=== RUN   TestManualTransformFile
    run.go:180: Remote "Dropbox root 'rclone-test-ramebor2yuca'", Local "Local file system at /tmp/rclone953504361", Modify Window "1s"
2025/09/05 03:58:35 DEBUG : Reset feature "Copy"
2025/09/05 03:58:35 DEBUG : Reset feature "Move"
2025/09/05 03:58:35 DEBUG : Reset feature "Copy"
2025/09/05 03:58:35 DEBUG : Reset feature "Move"
2025/09/05 03:58:35 DEBUG : empty_dir: Making directory with metadata
2025/09/05 03:58:35 INFO  : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2025/09/05 03:58:35 DEBUG : toe: transformed to: tictactoe
2025/09/05 03:58:35 DEBUG : empty_dir: transformed to: tictacempty_dir
2025/09/05 03:58:35 DEBUG : empty_dir: transformed to: tictacempty_dir
2025/09/05 03:58:35 DEBUG : empty_dir: transformed to: tictacempty_dir
2025/09/05 03:58:35 INFO  : tictacempty_dir: Making directory
2025/09/05 03:58:36 DEBUG : empty_dir: transformed to: tictacempty_dir
2025/09/05 03:58:36 DEBUG : toe: transformed to: tictactoe
2025/09/05 03:58:36 DEBUG : toe: transformed to: tictactoe
2025/09/05 03:58:36 INFO  : tictactoe: Making directory
2025/09/05 03:58:38 DEBUG : toe: transformed to: tictactoe
2025/09/05 03:58:38 DEBUG : toe: transformed to: tictactoe
2025/09/05 03:58:38 DEBUG : toe/toe: transformed to: tictactoe/tictactoe
2025/09/05 03:58:38 INFO  : tictactoe/tictactoe: Making directory
2025/09/05 03:58:39 DEBUG : toe/toe: transformed to: tictactoe/tictactoe
2025/09/05 03:58:39 DEBUG : toe.txt: transformed to: tictactoe.txt
2025/09/05 03:58:39 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt
2025/09/05 03:58:39 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination
2025/09/05 03:58:39 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt
2025/09/05 03:58:39 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt
2025/09/05 03:58:39 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Waiting for checks to finish
2025/09/05 03:58:39 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Waiting for transfers to finish
2025/09/05 03:58:39 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 1/1
2025/09/05 03:58:40 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 2/1
2025/09/05 03:58:40 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Adding "tictactoe/tictactoe/tictactoe.txt" to batch
2025/09/05 03:58:41 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Batch idle for 500ms so committing
2025/09/05 03:58:41 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Committing sync batch length 1 starting with: tictactoe/tictactoe/tictactoe.txt
2025/09/05 03:58:41 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Committed sync batch length 1 starting with: tictactoe/tictactoe/tictactoe.txt
2025/09/05 03:58:41 DEBUG : toe/toe/toe.txt: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2025/09/05 03:58:41 INFO  : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt
2025/09/05 03:58:41 INFO  : toe/toe/toe.txt: Deleted
2025/09/05 03:58:41 INFO  : toe/toe: Removing directory
2025/09/05 03:58:41 INFO  : toe: Removing directory
2025/09/05 03:58:41 INFO  : empty_dir: Removing directory
2025/09/05 03:58:41 DEBUG : Local file system at /tmp/rclone953504361: deleted 3 directories
2025/09/05 03:58:43 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s)
2025/09/05 03:58:43 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt
2025/09/05 03:58:43 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt
2025/09/05 03:58:43 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt
2025/09/05 03:58:44 DEBUG : toe/toe/toe.txt: Uploading chunk 1/1
2025/09/05 03:58:44 DEBUG : toe/toe/toe.txt: Uploading chunk 2/1
2025/09/05 03:58:45 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Adding "toe/toe/toe.txt" to batch
2025/09/05 03:58:46 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Batch idle for 500ms so committing
2025/09/05 03:58:46 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Committing sync batch length 1 starting with: toe/toe/toe.txt
2025/09/05 03:58:46 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Committed sync batch length 1 starting with: toe/toe/toe.txt
2025/09/05 03:58:46 DEBUG : tictactoe/tictactoe/tictactoe.txt: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2025/09/05 03:58:46 INFO  : tictactoe/tictactoe/tictactoe.txt: Copied (new) to: toe/toe/toe.txt
2025/09/05 03:58:47 INFO  : tictactoe/tictactoe/tictactoe.txt: Deleted
--- PASS: TestManualTransformFile (20.18s)
=== RUN   TestBase64
    run.go:180: Remote "Dropbox root 'rclone-test-ramebor2yuca'", Local "Local file system at /tmp/rclone953504361", Modify Window "1s"
2025/09/05 03:58:55 DEBUG : toe: transformed to: dG9l
2025/09/05 03:58:55 DEBUG : toe: transformed to: dG9l
2025/09/05 03:58:55 INFO  : dG9l: Making directory
2025/09/05 03:58:56 DEBUG : toe: transformed to: dG9l
2025/09/05 03:58:56 DEBUG : toe: transformed to: dG9l
2025/09/05 03:58:56 DEBUG : toe/toe: transformed to: dG9l/dG9l
2025/09/05 03:58:56 INFO  : dG9l/dG9l: Making directory
2025/09/05 03:58:57 DEBUG : toe/toe: transformed to: dG9l/dG9l
2025/09/05 03:58:57 DEBUG : toe.txt: transformed to: dG9lLnR4dA==
2025/09/05 03:58:57 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA==
2025/09/05 03:58:57 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination
2025/09/05 03:58:57 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA==
2025/09/05 03:58:57 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Waiting for checks to finish
2025/09/05 03:58:57 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Waiting for transfers to finish
2025/09/05 03:58:57 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 1/1
2025/09/05 03:58:58 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 2/1
2025/09/05 03:58:58 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Adding "dG9l/dG9l/dG9lLnR4dA==" to batch
2025/09/05 03:58:59 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Batch idle for 500ms so committing
2025/09/05 03:58:59 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Committing sync batch length 1 starting with: dG9l/dG9l/dG9lLnR4dA==
2025/09/05 03:58:59 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Committed sync batch length 1 starting with: dG9l/dG9l/dG9lLnR4dA==
2025/09/05 03:58:59 DEBUG : toe/toe/toe.txt: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2025/09/05 03:58:59 INFO  : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA==
2025/09/05 03:58:59 DEBUG : Waiting for deletions to finish
2025/09/05 03:59:00 DEBUG : dG9l: transformed to: toe
2025/09/05 03:59:00 DEBUG : dG9l: transformed to: toe
2025/09/05 03:59:00 INFO  : toe: Set directory modification time (using SetModTime)
2025/09/05 03:59:00 DEBUG : Added delayed dir = "toe", newDst=toe
2025/09/05 03:59:01 DEBUG : dG9l: transformed to: toe
2025/09/05 03:59:01 DEBUG : dG9l/dG9l: transformed to: toe/toe
2025/09/05 03:59:01 INFO  : toe/toe: Set directory modification time (using SetModTime)
2025/09/05 03:59:01 DEBUG : Added delayed dir = "toe/toe", newDst=toe/toe
2025/09/05 03:59:01 DEBUG : dG9lLnR4dA==: transformed to: toe.txt
2025/09/05 03:59:01 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Size and modification time the same (differ by 499.999999ms, within tolerance 1s)
2025/09/05 03:59:01 DEBUG : Local file system at /tmp/rclone953504361: Waiting for checks to finish
2025/09/05 03:59:01 DEBUG : dG9l/dG9l/dG9lLnR4dA==: transformed to: toe/toe/toe.txt
2025/09/05 03:59:01 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Unchanged skipping
2025/09/05 03:59:01 DEBUG : Local file system at /tmp/rclone953504361: Waiting for transfers to finish
2025/09/05 03:59:01 DEBUG : Waiting for deletions to finish
--- PASS: TestBase64 (10.69s)
PASS
2025/09/05 03:59:06 DEBUG : Dropbox root 'rclone-test-ramebor2yuca': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestBase64|TestManualTransformFile|TestTransformFile)$'" - Finished OK in 2m20.963250923s (try 4/5)