"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyNoEmptyDirectories|TestMoveOverSelf|TestServerSideMoveOverSelf|TestSyncBackupDir|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoUpdateDirModtime|TestSyncSetDelayedModTimes|TestTransformFile)$|^TestTransform$/^lowercase$'" - Starting (try 2/5) 2025/10/02 05:05:06 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-zixirar8xoju" 2025/10/02 05:05:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/02 05:05:06 DEBUG : Creating backend with remote "/tmp/rclone2789497499" === RUN TestCopyNoEmptyDirectories run.go:180: Remote "files root 'rclone-test-zixirar8xoju'", Local "Local file system at /tmp/rclone2789497499", Modify Window "1s" 2025/10/02 05:05:07 INFO : sub dir2: Making directory 2025/10/02 05:05:07 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/10/02 05:05:07 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/10/02 05:05:07 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/10/02 05:05:07 DEBUG : Added delayed dir = "sub dir", newDst= 2025/10/02 05:05:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/10/02 05:05:07 DEBUG : Added delayed dir = "sub dir2/sub sub dir2", newDst= 2025/10/02 05:05:07 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for checks to finish 2025/10/02 05:05:07 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for transfers to finish 2025/10/02 05:05:09 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/10/02 05:05:09 INFO : sub dir/hello world: Copied (new) 2025/10/02 05:05:09 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyNoEmptyDirectories (3.79s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "files root 'rclone-test-zixirar8xoju'", Local "Local file system at /tmp/rclone2789497499", Modify Window "1s" 2025/10/02 05:05:10 DEBUG : sub dir no update dir modtime: Making directory with metadata 2025/10/02 05:05:10 INFO : sub dir no update dir modtime: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/10/02 05:05:11 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for checks to finish 2025/10/02 05:05:11 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for transfers to finish 2025/10/02 05:05:11 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoUpdateDirModtime (1.52s) === RUN TestSyncEmptyDirectories run.go:180: Remote "files root 'rclone-test-zixirar8xoju'", Local "Local file system at /tmp/rclone2789497499", Modify Window "1s" 2025/10/02 05:05:12 DEBUG : sub dir2: Making directory with metadata 2025/10/02 05:05:12 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/10/02 05:05:12 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:12 INFO : sub dir2: Making directory 2025/10/02 05:05:12 INFO : sub dir2: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/10/02 05:05:12 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/10/02 05:05:12 INFO : sub dir: Making directory 2025/10/02 05:05:13 INFO : sub dir: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2025/10/02 05:05:13 DEBUG : Added delayed dir = "sub dir", newDst= 2025/10/02 05:05:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/10/02 05:05:13 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for checks to finish 2025/10/02 05:05:13 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for transfers to finish 2025/10/02 05:05:13 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/10/02 05:05:13 INFO : sub dir/hello world: Copied (new) 2025/10/02 05:05:13 DEBUG : Waiting for deletions to finish 2025/10/02 05:05:13 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:13 INFO : sub dir2: Set directory modification time (using DirSetModTime) --- PASS: TestSyncEmptyDirectories (3.13s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "files root 'rclone-test-zixirar8xoju'", Local "Local file system at /tmp/rclone2789497499", Modify Window "1s" 2025/10/02 05:05:15 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:15 INFO : a1: Making directory 2025/10/02 05:05:15 INFO : a1: Made directory with modification time 2001-02-03 04:19:06.499999999 +0000 UTC 2025/10/02 05:05:15 DEBUG : Added delayed dir = "a1", newDst= 2025/10/02 05:05:15 INFO : a1/b1: Making directory 2025/10/02 05:05:16 INFO : a1/b1: Made directory with modification time 2001-02-03 04:18:06.499999999 +0000 UTC 2025/10/02 05:05:16 DEBUG : Added delayed dir = "a1/b1", newDst= 2025/10/02 05:05:16 INFO : a1/b2: Making directory 2025/10/02 05:05:16 INFO : a1/b2: Made directory with modification time 2001-02-03 04:09:06.499999999 +0000 UTC 2025/10/02 05:05:16 DEBUG : Added delayed dir = "a1/b2", newDst= 2025/10/02 05:05:16 INFO : a1/b2/c1: Making directory 2025/10/02 05:05:16 INFO : a1/b1/c1: Making directory 2025/10/02 05:05:16 INFO : a1/b2/c1: Made directory with modification time 2001-02-03 04:08:06.499999999 +0000 UTC 2025/10/02 05:05:16 DEBUG : Added delayed dir = "a1/b2/c1", newDst= 2025/10/02 05:05:16 INFO : a1/b2/c1/d1: Making directory 2025/10/02 05:05:16 INFO : a1/b1/c1: Made directory with modification time 2001-02-03 04:17:06.499999999 +0000 UTC 2025/10/02 05:05:16 DEBUG : Added delayed dir = "a1/b1/c1", newDst= 2025/10/02 05:05:16 INFO : a1/b1/c1/d1: Making directory 2025/10/02 05:05:17 INFO : a1/b2/c1/d1: Made directory with modification time 2001-02-03 04:07:06.499999999 +0000 UTC 2025/10/02 05:05:17 DEBUG : Added delayed dir = "a1/b2/c1/d1", newDst= 2025/10/02 05:05:17 INFO : a1/b2/c1/d1/e1: Making directory 2025/10/02 05:05:17 INFO : a1/b1/c1/d1: Made directory with modification time 2001-02-03 04:16:06.499999999 +0000 UTC 2025/10/02 05:05:17 DEBUG : Added delayed dir = "a1/b1/c1/d1", newDst= 2025/10/02 05:05:17 INFO : a1/b1/c1/d2: Making directory 2025/10/02 05:05:17 INFO : a1/b2/c1/d1/e1: Made directory with modification time 2001-02-03 04:06:06.499999999 +0000 UTC 2025/10/02 05:05:17 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1", newDst= 2025/10/02 05:05:17 INFO : a1/b2/c1/d1/e1/f1: Making directory 2025/10/02 05:05:17 INFO : a1/b1/c1/d2: Made directory with modification time 2001-02-03 04:13:06.499999999 +0000 UTC 2025/10/02 05:05:17 DEBUG : Added delayed dir = "a1/b1/c1/d2", newDst= 2025/10/02 05:05:17 INFO : a1/b1/c1/d1/e1: Making directory 2025/10/02 05:05:17 INFO : a1/b1/c1/d2/e1: Making directory 2025/10/02 05:05:17 INFO : a1/b2/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2025/10/02 05:05:17 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1/f1", newDst= 2025/10/02 05:05:17 INFO : a1/b1/c1/d2/e1: Made directory with modification time 2001-02-03 04:12:06.499999999 +0000 UTC 2025/10/02 05:05:17 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1", newDst= 2025/10/02 05:05:17 INFO : a1/b1/c1/d2/e1/f1: Making directory 2025/10/02 05:05:17 INFO : a1/b1/c1/d1/e1: Made directory with modification time 2001-02-03 04:15:06.499999999 +0000 UTC 2025/10/02 05:05:17 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1", newDst= 2025/10/02 05:05:17 INFO : a1/b1/c1/d1/e1/f1: Making directory 2025/10/02 05:05:18 INFO : a1/b1/c1/d2/e1/f1: Made directory with modification time 2001-02-03 04:11:06.499999999 +0000 UTC 2025/10/02 05:05:18 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f1", newDst= 2025/10/02 05:05:18 INFO : a1/b1/c1/d2/e1/f2: Making directory 2025/10/02 05:05:18 INFO : a1/b1/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:14:06.499999999 +0000 UTC 2025/10/02 05:05:18 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1/f1", newDst= 2025/10/02 05:05:18 INFO : a1/b1/c1/d2/e1/f2: Made directory with modification time 2001-02-03 04:10:06.499999999 +0000 UTC 2025/10/02 05:05:18 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f2", newDst= 2025/10/02 05:05:18 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for checks to finish 2025/10/02 05:05:18 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for transfers to finish 2025/10/02 05:05:18 DEBUG : Waiting for deletions to finish 2025/10/02 05:05:18 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:18 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:18 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:18 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:18 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:18 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:18 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:18 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:18 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:18 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:19 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:19 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:19 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:19 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:19 INFO : a1: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:23 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/a1/b2/c1/d1/e1 not empty`) 2025/10/02 05:05:23 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/10/02 05:05:23 DEBUG : pacer: Reducing sleep to 15ms 2025/10/02 05:05:23 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/a1/b2/c1/d1 not empty`) 2025/10/02 05:05:23 DEBUG : pacer: Rate limited, increasing sleep to 30ms 2025/10/02 05:05:24 DEBUG : pacer: Reducing sleep to 22.5ms 2025/10/02 05:05:24 DEBUG : pacer: Reducing sleep to 16.875ms 2025/10/02 05:05:24 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/a1/b2 not empty`) 2025/10/02 05:05:24 DEBUG : pacer: Rate limited, increasing sleep to 33.75ms 2025/10/02 05:05:24 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/a1/b2 not empty`) 2025/10/02 05:05:24 DEBUG : pacer: Rate limited, increasing sleep to 67.5ms 2025/10/02 05:05:24 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/a1/b2 not empty`) 2025/10/02 05:05:24 DEBUG : pacer: Rate limited, increasing sleep to 135ms 2025/10/02 05:05:24 DEBUG : pacer: low level retry 4/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/a1/b2 not empty`) 2025/10/02 05:05:24 DEBUG : pacer: Rate limited, increasing sleep to 270ms 2025/10/02 05:05:24 DEBUG : pacer: low level retry 5/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/a1/b2 not empty`) 2025/10/02 05:05:24 DEBUG : pacer: Rate limited, increasing sleep to 540ms 2025/10/02 05:05:25 DEBUG : pacer: low level retry 6/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/a1/b2 not empty`) 2025/10/02 05:05:25 DEBUG : pacer: Rate limited, increasing sleep to 1.08s 2025/10/02 05:05:25 DEBUG : pacer: Reducing sleep to 810ms 2025/10/02 05:05:26 DEBUG : pacer: Reducing sleep to 607.5ms 2025/10/02 05:05:27 DEBUG : pacer: Reducing sleep to 455.625ms 2025/10/02 05:05:28 DEBUG : pacer: Reducing sleep to 341.71875ms 2025/10/02 05:05:28 DEBUG : pacer: Reducing sleep to 256.289062ms 2025/10/02 05:05:28 DEBUG : pacer: Reducing sleep to 192.216796ms 2025/10/02 05:05:29 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/a1/b1/c1/d1/e1 not empty`) 2025/10/02 05:05:29 DEBUG : pacer: Rate limited, increasing sleep to 384.433592ms 2025/10/02 05:05:29 DEBUG : pacer: Reducing sleep to 288.325194ms 2025/10/02 05:05:29 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/a1/b1/c1/d1 not empty`) 2025/10/02 05:05:29 DEBUG : pacer: Rate limited, increasing sleep to 576.650388ms 2025/10/02 05:05:30 DEBUG : pacer: Reducing sleep to 432.487791ms 2025/10/02 05:05:30 DEBUG : pacer: Reducing sleep to 324.365843ms 2025/10/02 05:05:31 DEBUG : pacer: Reducing sleep to 243.274382ms 2025/10/02 05:05:31 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/a1 not empty`) 2025/10/02 05:05:31 DEBUG : pacer: Rate limited, increasing sleep to 486.548764ms 2025/10/02 05:05:31 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/a1 not empty`) 2025/10/02 05:05:31 DEBUG : pacer: Rate limited, increasing sleep to 973.097528ms 2025/10/02 05:05:32 DEBUG : pacer: Reducing sleep to 729.823146ms 2025/10/02 05:05:32 DEBUG : pacer: Reducing sleep to 547.367359ms --- PASS: TestSyncSetDelayedModTimes (17.65s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "files root 'rclone-test-zixirar8xoju'", Local "Local file system at /tmp/rclone2789497499", Modify Window "1s" 2025/10/02 05:05:33 INFO : sub dir2: Making directory 2025/10/02 05:05:33 DEBUG : pacer: Reducing sleep to 410.525519ms 2025/10/02 05:05:33 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/10/02 05:05:33 DEBUG : Added delayed dir = "sub dir", newDst= 2025/10/02 05:05:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/10/02 05:05:33 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for checks to finish 2025/10/02 05:05:33 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for transfers to finish 2025/10/02 05:05:35 DEBUG : pacer: Reducing sleep to 307.894139ms 2025/10/02 05:05:35 DEBUG : pacer: Reducing sleep to 230.920604ms 2025/10/02 05:05:35 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/10/02 05:05:35 INFO : sub dir/hello world: Copied (new) 2025/10/02 05:05:35 DEBUG : Waiting for deletions to finish 2025/10/02 05:05:35 DEBUG : pacer: Reducing sleep to 173.190453ms 2025/10/02 05:05:35 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:35 DEBUG : pacer: Reducing sleep to 129.892839ms 2025/10/02 05:05:35 DEBUG : pacer: Reducing sleep to 97.419629ms 2025/10/02 05:05:36 DEBUG : pacer: Reducing sleep to 73.064721ms 2025/10/02 05:05:36 DEBUG : pacer: Reducing sleep to 54.79854ms 2025/10/02 05:05:36 DEBUG : pacer: Reducing sleep to 41.098905ms 2025/10/02 05:05:36 DEBUG : pacer: Reducing sleep to 30.824178ms 2025/10/02 05:05:36 DEBUG : pacer: Reducing sleep to 23.118133ms --- PASS: TestSyncNoEmptyDirectories (3.70s) === RUN TestMoveOverSelf run.go:180: Remote "files root 'rclone-test-zixirar8xoju'", Local "Local file system at /tmp/rclone2789497499", Modify Window "1s" 2025/10/02 05:05:37 DEBUG : pacer: Reducing sleep to 17.338599ms 2025/10/02 05:05:37 DEBUG : pacer: Reducing sleep to 13.003949ms 2025/10/02 05:05:37 DEBUG : pacer: Reducing sleep to 10ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:679 Error: Should be true Test: TestMoveOverSelf Messages: listing wrong, want sub dir/hello world (11) got fstest.go:203: Not found "sub dir/hello world" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:679 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 0 actual : 1 Test: TestMoveOverSelf Messages: 1 objects not found 2025/10/02 05:05:45 DEBUG : Added delayed dir = "sub dir", newDst= 2025/10/02 05:05:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/10/02 05:05:45 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for checks to finish 2025/10/02 05:05:45 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for transfers to finish 2025/10/02 05:05:46 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/10/02 05:05:46 INFO : sub dir/hello world: Copied (new) 2025/10/02 05:05:46 INFO : sub dir/hello world: Deleted 2025/10/02 05:05:47 INFO : sub dir: Set directory modification time (using DirSetModTime) --- FAIL: TestMoveOverSelf (11.58s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "files root 'rclone-test-zixirar8xoju'", Local "Local file system at /tmp/rclone2789497499", Modify Window "1s" 2025/10/02 05:05:49 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-bibocux2kuji" sync_test.go:701: Server side copy (if possible) files root 'rclone-test-zixirar8xoju' -> files root 'rclone-test-bibocux2kuji' 2025/10/02 05:05:50 DEBUG : Added delayed dir = "sub dir", newDst= 2025/10/02 05:05:50 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/10/02 05:05:50 DEBUG : files root 'rclone-test-bibocux2kuji': Waiting for checks to finish 2025/10/02 05:05:50 DEBUG : files root 'rclone-test-bibocux2kuji': Waiting for transfers to finish 2025/10/02 05:05:51 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/10/02 05:05:51 DEBUG : sub dir/hello world: Src hash empty - aborting Dst hash check 2025/10/02 05:05:51 INFO : sub dir/hello world: Copied (server-side copy) 2025/10/02 05:05:51 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:53 DEBUG : files root 'rclone-test-bibocux2kuji': Using server-side directory move 2025/10/02 05:05:53 INFO : files root 'rclone-test-bibocux2kuji': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/10/02 05:05:53 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:53 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/10/02 05:05:54 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/10/02 05:05:54 DEBUG : files root 'rclone-test-bibocux2kuji': Waiting for checks to finish 2025/10/02 05:05:54 DEBUG : files root 'rclone-test-bibocux2kuji': Waiting for transfers to finish 2025/10/02 05:05:54 INFO : sub dir/hello world: Deleted 2025/10/02 05:05:54 INFO : sub dir/hello world: Moved (server-side) 2025/10/02 05:05:54 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:56 DEBUG : testing file moves 2025/10/02 05:05:56 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:56 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/10/02 05:05:56 DEBUG : files root 'rclone-test-bibocux2kuji': Waiting for checks to finish 2025/10/02 05:05:56 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/10/02 05:05:56 DEBUG : files root 'rclone-test-bibocux2kuji': Waiting for transfers to finish 2025/10/02 05:05:57 INFO : sub dir/hello world: Deleted 2025/10/02 05:05:57 INFO : sub dir/hello world: Moved (server-side) 2025/10/02 05:05:57 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/10/02 05:05:58 DEBUG : files root 'rclone-test-bibocux2kuji': Purge remote --- PASS: TestServerSideMoveOverSelf (10.81s) === RUN TestSyncBackupDir run.go:180: Remote "files root 'rclone-test-zixirar8xoju'", Local "Local file system at /tmp/rclone2789497499", Modify Window "1s" 2025/10/02 05:06:01 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-zixirar8xoju/dst" 2025/10/02 05:06:02 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-zixirar8xoju/backup" 2025/10/02 05:06:03 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/10/02 05:06:03 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:03 DEBUG : two: Unchanged skipping 2025/10/02 05:06:03 DEBUG : files root 'rclone-test-zixirar8xoju/dst': Waiting for checks to finish 2025/10/02 05:06:04 INFO : one: Moved (server-side) 2025/10/02 05:06:04 DEBUG : files root 'rclone-test-zixirar8xoju/dst': Waiting for transfers to finish 2025/10/02 05:06:06 DEBUG : one: Dst hash empty - aborting Src hash check 2025/10/02 05:06:06 INFO : one: Copied (new) 2025/10/02 05:06:06 DEBUG : Waiting for deletions to finish 2025/10/02 05:06:07 INFO : three.txt: Moved (server-side) 2025/10/02 05:06:07 INFO : three.txt: Moved into backup dir 2025/10/02 05:06:10 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/10/02 05:06:10 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:10 DEBUG : two: Unchanged skipping 2025/10/02 05:06:10 DEBUG : files root 'rclone-test-zixirar8xoju/dst': Waiting for checks to finish 2025/10/02 05:06:10 INFO : one: Deleted 2025/10/02 05:06:11 INFO : one: Moved (server-side) 2025/10/02 05:06:11 DEBUG : files root 'rclone-test-zixirar8xoju/dst': Waiting for transfers to finish 2025/10/02 05:06:13 DEBUG : one: Dst hash empty - aborting Src hash check 2025/10/02 05:06:13 INFO : one: Copied (new) 2025/10/02 05:06:13 DEBUG : Waiting for deletions to finish 2025/10/02 05:06:13 INFO : three.txt: Deleted 2025/10/02 05:06:14 INFO : three.txt: Moved (server-side) 2025/10/02 05:06:14 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (17.73s) === RUN TestSyncConcurrentDelete run.go:180: Remote "files root 'rclone-test-zixirar8xoju'", Local "Local file system at /tmp/rclone2789497499", Modify Window "1s" 2025/10/02 05:06:52 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both0: Unchanged skipping 2025/10/02 05:06:52 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for checks to finish 2025/10/02 05:06:52 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both10: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both14: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both11: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both16: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both17: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both13: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both18: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both1: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both2: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both15: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both12: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both3: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both6: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both7: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both8: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:06:52 DEBUG : both9: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both4: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both19: Unchanged skipping 2025/10/02 05:06:52 DEBUG : both5: Unchanged skipping 2025/10/02 05:06:52 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for transfers to finish 2025/10/02 05:06:52 DEBUG : Waiting for deletions to finish 2025/10/02 05:06:52 INFO : only8: Deleted 2025/10/02 05:06:52 INFO : only12: Deleted 2025/10/02 05:06:52 INFO : only13: Deleted 2025/10/02 05:06:52 INFO : only11: Deleted 2025/10/02 05:06:53 INFO : only15: Deleted 2025/10/02 05:06:53 INFO : only1: Deleted 2025/10/02 05:06:53 INFO : only9: Deleted 2025/10/02 05:06:53 INFO : only19: Deleted 2025/10/02 05:06:53 INFO : only3: Deleted 2025/10/02 05:06:53 INFO : only6: Deleted 2025/10/02 05:06:53 INFO : only0: Deleted 2025/10/02 05:06:53 INFO : only10: Deleted 2025/10/02 05:06:53 INFO : only17: Deleted 2025/10/02 05:06:53 INFO : only18: Deleted 2025/10/02 05:06:53 INFO : only14: Deleted 2025/10/02 05:06:53 INFO : only16: Deleted 2025/10/02 05:06:53 INFO : only2: Deleted 2025/10/02 05:06:53 INFO : only4: Deleted 2025/10/02 05:06:53 INFO : only5: Deleted 2025/10/02 05:06:53 INFO : only7: Deleted --- PASS: TestSyncConcurrentDelete (40.61s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "files root 'rclone-test-zixirar8xoju'", Local "Local file system at /tmp/rclone2789497499", Modify Window "1s" 2025/10/02 05:07:28 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for checks to finish 2025/10/02 05:07:28 DEBUG : both0: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both13: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both11: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both15: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both16: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both17: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both18: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both19: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both10: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both12: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both14: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both1: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both2: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both6: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both7: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both8: Unchanged skipping 2025/10/02 05:07:28 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : both9: Unchanged skipping 2025/10/02 05:07:28 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:28 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : both3: Unchanged skipping 2025/10/02 05:07:28 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : both5: Unchanged skipping 2025/10/02 05:07:28 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : both4: Unchanged skipping 2025/10/02 05:07:28 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/10/02 05:07:28 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for transfers to finish 2025/10/02 05:07:29 DEBUG : only11: Dst hash empty - aborting Src hash check 2025/10/02 05:07:29 INFO : only11: Copied (replaced existing) 2025/10/02 05:07:29 DEBUG : only10: Dst hash empty - aborting Src hash check 2025/10/02 05:07:29 INFO : only10: Copied (replaced existing) 2025/10/02 05:07:30 DEBUG : only0: Dst hash empty - aborting Src hash check 2025/10/02 05:07:30 INFO : only0: Copied (replaced existing) 2025/10/02 05:07:30 DEBUG : only13: Dst hash empty - aborting Src hash check 2025/10/02 05:07:30 INFO : only13: Copied (replaced existing) 2025/10/02 05:07:30 DEBUG : only14: Dst hash empty - aborting Src hash check 2025/10/02 05:07:30 INFO : only14: Copied (replaced existing) 2025/10/02 05:07:31 DEBUG : only16: Dst hash empty - aborting Src hash check 2025/10/02 05:07:31 INFO : only16: Copied (replaced existing) 2025/10/02 05:07:31 DEBUG : only17: Dst hash empty - aborting Src hash check 2025/10/02 05:07:31 INFO : only17: Copied (replaced existing) 2025/10/02 05:07:31 DEBUG : only18: Dst hash empty - aborting Src hash check 2025/10/02 05:07:31 INFO : only18: Copied (replaced existing) 2025/10/02 05:07:31 DEBUG : only15: Dst hash empty - aborting Src hash check 2025/10/02 05:07:31 INFO : only15: Copied (replaced existing) 2025/10/02 05:07:32 DEBUG : only19: Dst hash empty - aborting Src hash check 2025/10/02 05:07:32 INFO : only19: Copied (replaced existing) 2025/10/02 05:07:32 DEBUG : only4: Dst hash empty - aborting Src hash check 2025/10/02 05:07:32 INFO : only4: Copied (replaced existing) 2025/10/02 05:07:32 DEBUG : only5: Dst hash empty - aborting Src hash check 2025/10/02 05:07:32 INFO : only5: Copied (replaced existing) 2025/10/02 05:07:33 DEBUG : only12: Dst hash empty - aborting Src hash check 2025/10/02 05:07:33 INFO : only12: Copied (replaced existing) 2025/10/02 05:07:33 DEBUG : only1: Dst hash empty - aborting Src hash check 2025/10/02 05:07:33 INFO : only1: Copied (replaced existing) 2025/10/02 05:07:33 DEBUG : only2: Dst hash empty - aborting Src hash check 2025/10/02 05:07:33 INFO : only2: Copied (replaced existing) 2025/10/02 05:07:33 DEBUG : only3: Dst hash empty - aborting Src hash check 2025/10/02 05:07:33 INFO : only3: Copied (replaced existing) 2025/10/02 05:07:34 DEBUG : only8: Dst hash empty - aborting Src hash check 2025/10/02 05:07:34 INFO : only8: Copied (replaced existing) 2025/10/02 05:07:34 DEBUG : only7: Dst hash empty - aborting Src hash check 2025/10/02 05:07:34 INFO : only7: Copied (replaced existing) 2025/10/02 05:07:34 DEBUG : only6: Dst hash empty - aborting Src hash check 2025/10/02 05:07:34 INFO : only6: Copied (replaced existing) 2025/10/02 05:07:34 DEBUG : only9: Dst hash empty - aborting Src hash check 2025/10/02 05:07:34 INFO : only9: Copied (replaced existing) 2025/10/02 05:07:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (44.06s) === RUN TestTransform === RUN TestTransform/lowercase run.go:180: Remote "files root 'rclone-test-zixirar8xoju'", Local "Local file system at /tmp/rclone2789497499", Modify Window "1s" 2025/10/02 05:07:57 DEBUG : Waiting for deletions to finish 2025/10/02 05:07:57 DEBUG : apple: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : apple: Excluded 2025/10/02 05:07:57 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : appleappleapplebanana: Excluded 2025/10/02 05:07:57 DEBUG : banana: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : banana: Excluded 2025/10/02 05:07:57 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : splitbananasplit: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0000-abcdefg.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0001-bcdefgh.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0002-cdefghi.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0003-defghij.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0004-efghijk.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0005-fghijkl.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0006-ghijklm.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0007-1234567.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0008-2345678.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0009-3456789.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0010-456789.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0011-56789;.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0012-6789;.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0013-789;=.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0014-89;=.txt: Excluded 2025/10/02 05:07:57 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/10/02 05:07:57 DEBUG : dir1/0015-9;=.txt: Excluded 2025/10/02 05:07:58 DEBUG : appleappleapplebanana: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : apple: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : appleappleapplebanana: Unchanged skipping 2025/10/02 05:07:58 DEBUG : apple: Unchanged skipping 2025/10/02 05:07:58 DEBUG : banana: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : banana: Unchanged skipping 2025/10/02 05:07:58 INFO : dir1: Set directory modification time (using DirSetModTime) 2025/10/02 05:07:58 DEBUG : Added delayed dir = "dir1", newDst=dir1 2025/10/02 05:07:58 DEBUG : splitbananasplit: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : splitbananasplit: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for checks to finish 2025/10/02 05:07:58 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:58 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/10/02 05:07:58 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for transfers to finish 2025/10/02 05:07:58 DEBUG : Waiting for deletions to finish 2025/10/02 05:07:58 DEBUG : Waiting for deletions to finish 2025/10/02 05:07:58 DEBUG : apple: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : apple: Excluded 2025/10/02 05:07:58 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : appleappleapplebanana: Excluded 2025/10/02 05:07:58 DEBUG : banana: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : banana: Excluded 2025/10/02 05:07:58 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : splitbananasplit: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0000-abcdefg.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0001-bcdefgh.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0002-cdefghi.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0003-defghij.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0004-efghijk.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0005-fghijkl.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0006-ghijklm.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0007-1234567.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0008-2345678.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0009-3456789.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0010-456789.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0011-56789;.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0012-6789;.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0013-789;=.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0014-89;=.txt: Excluded 2025/10/02 05:07:58 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/10/02 05:07:58 DEBUG : dir1/0015-9;=.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1: Directory modification time the same (differ by -111.785819ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : Added delayed dir = "dir1", newDst=dir1 2025/10/02 05:07:59 DEBUG : banana: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : appleappleapplebanana: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : banana: Unchanged skipping 2025/10/02 05:07:59 DEBUG : appleappleapplebanana: Unchanged skipping 2025/10/02 05:07:59 DEBUG : splitbananasplit: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : splitbananasplit: Unchanged skipping 2025/10/02 05:07:59 DEBUG : apple: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : apple: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/02 05:07:59 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/10/02 05:07:59 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for checks to finish 2025/10/02 05:07:59 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for transfers to finish 2025/10/02 05:07:59 DEBUG : Waiting for deletions to finish 2025/10/02 05:07:59 DEBUG : Waiting for deletions to finish 2025/10/02 05:07:59 DEBUG : apple: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : apple: Excluded 2025/10/02 05:07:59 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : appleappleapplebanana: Excluded 2025/10/02 05:07:59 DEBUG : banana: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : banana: Excluded 2025/10/02 05:07:59 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : splitbananasplit: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0000-abcdefg.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0001-bcdefgh.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0002-cdefghi.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0003-defghij.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0004-efghijk.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0005-fghijkl.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0006-ghijklm.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0007-1234567.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0008-2345678.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0009-3456789.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0010-456789.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0011-56789;.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0012-6789;.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0013-789;=.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0014-89;=.txt: Excluded 2025/10/02 05:07:59 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/10/02 05:07:59 DEBUG : dir1/0015-9;=.txt: Excluded --- PASS: TestTransform (22.87s) --- PASS: TestTransform/lowercase (22.87s) === RUN TestTransformFile run.go:180: Remote "files root 'rclone-test-zixirar8xoju'", Local "Local file system at /tmp/rclone2789497499", Modify Window "1s" 2025/10/02 05:08:04 DEBUG : empty_dir: Making directory with metadata 2025/10/02 05:08:04 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/10/02 05:08:04 DEBUG : toe: transformed to: tictactoe 2025/10/02 05:08:04 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/10/02 05:08:04 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/10/02 05:08:04 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/10/02 05:08:04 INFO : tictacempty_dir: Making directory 2025/10/02 05:08:04 INFO : tictacempty_dir: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2025/10/02 05:08:04 DEBUG : Added delayed dir = "tictacempty_dir", newDst= 2025/10/02 05:08:04 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/10/02 05:08:04 DEBUG : toe: transformed to: tictactoe 2025/10/02 05:08:04 DEBUG : toe: transformed to: tictactoe 2025/10/02 05:08:04 INFO : tictactoe: Making directory 2025/10/02 05:08:05 INFO : tictactoe: Made directory with modification time 2025-10-02 05:08:04.473807119 +0000 UTC 2025/10/02 05:08:05 DEBUG : Added delayed dir = "tictactoe", newDst= 2025/10/02 05:08:05 DEBUG : toe: transformed to: tictactoe 2025/10/02 05:08:05 DEBUG : toe: transformed to: tictactoe 2025/10/02 05:08:05 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/10/02 05:08:05 INFO : tictactoe/tictactoe: Making directory 2025/10/02 05:08:05 INFO : tictactoe/tictactoe: Made directory with modification time 2025-10-02 05:08:04.473807119 +0000 UTC 2025/10/02 05:08:05 DEBUG : Added delayed dir = "tictactoe/tictactoe", newDst= 2025/10/02 05:08:05 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/10/02 05:08:05 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/10/02 05:08:05 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/10/02 05:08:05 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/10/02 05:08:05 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/10/02 05:08:05 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/10/02 05:08:05 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for checks to finish 2025/10/02 05:08:05 DEBUG : files root 'rclone-test-zixirar8xoju': Waiting for transfers to finish 2025/10/02 05:08:06 DEBUG : tictactoe/tictactoe/tictactoe.txt: Dst hash empty - aborting Src hash check 2025/10/02 05:08:06 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/10/02 05:08:06 INFO : toe/toe/toe.txt: Deleted 2025/10/02 05:08:06 INFO : tictactoe/tictactoe: Set directory modification time (using DirSetModTime) 2025/10/02 05:08:06 INFO : tictacempty_dir: Set directory modification time (using DirSetModTime) 2025/10/02 05:08:06 INFO : tictactoe: Set directory modification time (using DirSetModTime) 2025/10/02 05:08:06 INFO : toe/toe: Removing directory 2025/10/02 05:08:06 INFO : toe: Removing directory 2025/10/02 05:08:06 INFO : empty_dir: Removing directory 2025/10/02 05:08:06 DEBUG : Local file system at /tmp/rclone2789497499: deleted 3 directories 2025/10/02 05:08:07 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/10/02 05:08:07 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/10/02 05:08:07 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/10/02 05:08:07 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2025/10/02 05:08:08 INFO : tictactoe/tictactoe/tictactoe.txt: Moved (server-side) to: toe/toe/toe.txt 2025/10/02 05:08:09 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/toe not empty`) 2025/10/02 05:08:09 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/10/02 05:08:09 DEBUG : pacer: Reducing sleep to 15ms 2025/10/02 05:08:09 DEBUG : pacer: Reducing sleep to 11.25ms 2025/10/02 05:08:09 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/tictactoe not empty`) 2025/10/02 05:08:09 DEBUG : pacer: Rate limited, increasing sleep to 22.5ms 2025/10/02 05:08:09 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/tictactoe not empty`) 2025/10/02 05:08:09 DEBUG : pacer: Rate limited, increasing sleep to 45ms 2025/10/02 05:08:10 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-zixirar8xoju/tictactoe not empty`) 2025/10/02 05:08:10 DEBUG : pacer: Rate limited, increasing sleep to 90ms 2025/10/02 05:08:10 DEBUG : pacer: Reducing sleep to 67.5ms 2025/10/02 05:08:10 DEBUG : pacer: Reducing sleep to 50.625ms 2025/10/02 05:08:10 DEBUG : pacer: Reducing sleep to 37.96875ms --- PASS: TestTransformFile (6.10s) FAIL 2025/10/02 05:08:10 DEBUG : files root 'rclone-test-zixirar8xoju': Purge remote 2025/10/02 05:08:10 DEBUG : pacer: Reducing sleep to 28.476562ms "./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyNoEmptyDirectories|TestMoveOverSelf|TestServerSideMoveOverSelf|TestSyncBackupDir|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoUpdateDirModtime|TestSyncSetDelayedModTimes|TestTransformFile)$|^TestTransform$/^lowercase$'" - Finished ERROR in 3m4.614636371s (try 2/5): exit status 1: Failed [TestMoveOverSelf]