"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyRedownload|TestFixCase|TestNoTag|TestServerSideMoveOverSelf|TestSyncBackupDirSuffixOnly|TestSyncMultipleCompareDest|TestSyncSuffixKeepExtension)$'" - Starting (try 2/5) 2025/10/31 02:31:40 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-loqujit3paxu" 2025/10/31 02:31:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/31 02:31:41 DEBUG : Creating backend with remote "/tmp/rclone882650323" === RUN TestServerSideMoveOverSelf run.go:185: Remote "files root 'rclone-test-loqujit3paxu'", Local "Local file system at /tmp/rclone882650323", Modify Window "1s" 2025/10/31 02:31:43 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-begoyah6huca" sync_test.go:701: Server side copy (if possible) files root 'rclone-test-loqujit3paxu' -> files root 'rclone-test-begoyah6huca' 2025/10/31 02:31:44 DEBUG : Added delayed dir = "sub dir", newDst= 2025/10/31 02:31:44 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/10/31 02:31:44 DEBUG : files root 'rclone-test-begoyah6huca': Waiting for checks to finish 2025/10/31 02:31:44 DEBUG : files root 'rclone-test-begoyah6huca': Waiting for transfers to finish 2025/10/31 02:31:47 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/10/31 02:31:47 INFO : sub dir/hello world: Copied (server-side copy) 2025/10/31 02:31:47 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/10/31 02:31:49 DEBUG : pacer: low level retry 1/10 (error Put "https://s3.amazonaws.com/objects.brickftp.com/metadata/126732/6932ef5d-736d-4bcb-aa53-7dc889a0a2ea?X-Amz-Algorithm=AWS4-HMAC-SHA256&X-Amz-Credential=AKIAIEWLY3MN4YGZQOWA%2F20251031%2Fus-east-1%2Fs3%2Faws4_request&X-Amz-Date=20251031T023148Z&X-Amz-Expires=900&X-Amz-SignedHeaders=host&partNumber=1&response-content-type=application%2Foctet-stream&uploadId=LQZtpiiwggjW8_bnnoat.5RSVz3q.MH8Zx7ZTQ2Ha9ZQuxM.0c_6pdBSVkMMTZXlb18MBg42DJ4fPhXMHh4ZqA_nEwCPLgkdF2MbJpV0gM0ACj03WCciWTf1t8XvVRuu&X-Amz-Signature=5932141f445158495966f07b12e9b72c5e9e2e908e85a10e47a6eb4f208e7ea4": net/http: HTTP/1.x transport connection broken: http: ContentLength=17 with Body length 0) 2025/10/31 02:31:49 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/10/31 02:31:50 DEBUG : pacer: Reducing sleep to 15ms 2025/10/31 02:31:50 DEBUG : pacer: Reducing sleep to 11.25ms 2025/10/31 02:31:50 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:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:710 Error: Should be true Test: TestServerSideMoveOverSelf Messages: listing wrong, want sub dir/hello world (17) got sub dir/hello world (0) fstest.go:143: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:149 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /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:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:710 Error: Should be true Test: TestServerSideMoveOverSelf Messages: files root 'rclone-test-loqujit3paxu'/sub dir/hello world: md5 hash incorrect - expecting "00321a507bdb420dbbd4f53c733b1505" got "d41d8cd98f00b204e9800998ecf8427e" fstest.go:143: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:149 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /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:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:710 Error: Should be true Test: TestServerSideMoveOverSelf Messages: files root 'rclone-test-loqujit3paxu'/sub dir/hello world: crc32 hash incorrect - expecting "0c611827" got "00000000" fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /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:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:710 Error: Not equal: expected: 17 actual : 0 Test: TestServerSideMoveOverSelf Messages: sub dir/hello world: size incorrect file=17 vs obj=0 2025/10/31 02:31:59 DEBUG : files root 'rclone-test-begoyah6huca': Using server-side directory move 2025/10/31 02:31:59 INFO : files root 'rclone-test-begoyah6huca': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/10/31 02:32:00 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/10/31 02:32:00 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/10/31 02:32:00 DEBUG : sub dir/hello world: Sizes differ (src 0 vs dst 11) 2025/10/31 02:32:00 DEBUG : files root 'rclone-test-begoyah6huca': Waiting for checks to finish 2025/10/31 02:32:00 DEBUG : files root 'rclone-test-begoyah6huca': Waiting for transfers to finish 2025/10/31 02:32:00 INFO : sub dir/hello world: Deleted 2025/10/31 02:32:01 INFO : sub dir/hello world: Moved (server-side) 2025/10/31 02:32:02 INFO : sub dir: Set directory modification time (using DirSetModTime) 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:356 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:717 Error: Should be true Test: TestServerSideMoveOverSelf Messages: listing wrong, want sub dir/hello world (17) got sub dir/hello world (0) fstest.go:143: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:149 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:356 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:717 Error: Should be true Test: TestServerSideMoveOverSelf Messages: files root 'rclone-test-begoyah6huca'/sub dir/hello world: md5 hash incorrect - expecting "00321a507bdb420dbbd4f53c733b1505" got "d41d8cd98f00b204e9800998ecf8427e" fstest.go:143: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:149 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:356 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:717 Error: Should be true Test: TestServerSideMoveOverSelf Messages: files root 'rclone-test-begoyah6huca'/sub dir/hello world: crc32 hash incorrect - expecting "0c611827" got "00000000" fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:356 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:717 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Not equal: expected: 17 actual : 0 Test: TestServerSideMoveOverSelf Messages: sub dir/hello world: size incorrect file=17 vs obj=0 2025/10/31 02:32:13 DEBUG : testing file moves 2025/10/31 02:32:14 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/10/31 02:32:14 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/10/31 02:32:14 DEBUG : files root 'rclone-test-begoyah6huca': Waiting for checks to finish 2025/10/31 02:32:14 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 0) 2025/10/31 02:32:14 DEBUG : files root 'rclone-test-begoyah6huca': Waiting for transfers to finish 2025/10/31 02:32:14 INFO : sub dir/hello world: Deleted 2025/10/31 02:32:15 INFO : sub dir/hello world: Moved (server-side) 2025/10/31 02:32:15 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/10/31 02:32:17 DEBUG : files root 'rclone-test-begoyah6huca': Purge remote --- FAIL: TestServerSideMoveOverSelf (37.27s) === RUN TestCopyRedownload run.go:185: Remote "files root 'rclone-test-loqujit3paxu'", Local "Local file system at /tmp/rclone882650323", Modify Window "1s" 2025/10/31 02:32:20 DEBUG : Added delayed dir = "sub dir", newDst= 2025/10/31 02:32:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/10/31 02:32:20 DEBUG : Local file system at /tmp/rclone882650323: Waiting for checks to finish 2025/10/31 02:32:20 DEBUG : Local file system at /tmp/rclone882650323: Waiting for transfers to finish 2025/10/31 02:32:20 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/10/31 02:32:20 DEBUG : sub dir/hello world.8a47773c.partial: renamed to: sub dir/hello world 2025/10/31 02:32:20 INFO : sub dir/hello world: Copied (new) 2025/10/31 02:32:20 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyRedownload (3.13s) === RUN TestSyncMultipleCompareDest run.go:185: Remote "files root 'rclone-test-loqujit3paxu'", Local "Local file system at /tmp/rclone882650323", Modify Window "1s" 2025/10/31 02:32:23 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-loqujit3paxu/dest" 2025/10/31 02:32:24 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-loqujit3paxu/pre-dest1" 2025/10/31 02:32:25 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-loqujit3paxu/pre-dest2" 2025/10/31 02:32:26 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/31 02:32:26 DEBUG : 1: Destination found in --compare-dest, skipping 2025/10/31 02:32:26 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/31 02:32:26 DEBUG : 2: Destination found in --compare-dest, skipping 2025/10/31 02:32:27 DEBUG : 3: Need to transfer - File not found at Destination 2025/10/31 02:32:27 DEBUG : files root 'rclone-test-loqujit3paxu/dest': Waiting for checks to finish 2025/10/31 02:32:27 DEBUG : files root 'rclone-test-loqujit3paxu/dest': Waiting for transfers to finish 2025/10/31 02:32:28 DEBUG : 3: md5 = eccbc87e4b5ce2fe28308fd9f2a7baf3 OK 2025/10/31 02:32:28 INFO : 3: Copied (new) 2025/10/31 02:32:28 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (10.39s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "files root 'rclone-test-loqujit3paxu'", Local "Local file system at /tmp/rclone882650323", Modify Window "1s" 2025/10/31 02:32:34 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-loqujit3paxu/dst" 2025/10/31 02:32:35 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/10/31 02:32:35 DEBUG : files root 'rclone-test-loqujit3paxu/dst': Waiting for checks to finish 2025/10/31 02:32:35 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/31 02:32:35 DEBUG : two: Unchanged skipping 2025/10/31 02:32:36 INFO : one: Moved (server-side) to: one.bak 2025/10/31 02:32:36 DEBUG : files root 'rclone-test-loqujit3paxu/dst': Waiting for transfers to finish 2025/10/31 02:32:37 DEBUG : one: Dst hash empty - aborting Src hash check 2025/10/31 02:32:37 INFO : one: Copied (new) 2025/10/31 02:32:37 DEBUG : Waiting for deletions to finish 2025/10/31 02:32:38 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/10/31 02:32:38 INFO : three.txt: Moved into backup dir 2025/10/31 02:32:41 DEBUG : one.bak: Excluded (Path Filter) 2025/10/31 02:32:41 DEBUG : one.bak: Excluded 2025/10/31 02:32:41 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/10/31 02:32:41 DEBUG : three.txt.bak: Excluded 2025/10/31 02:32:41 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/10/31 02:32:41 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/31 02:32:41 DEBUG : two: Unchanged skipping 2025/10/31 02:32:41 DEBUG : files root 'rclone-test-loqujit3paxu/dst': Waiting for checks to finish 2025/10/31 02:32:41 INFO : one.bak: Deleted 2025/10/31 02:32:41 INFO : one: Moved (server-side) to: one.bak 2025/10/31 02:32:41 DEBUG : files root 'rclone-test-loqujit3paxu/dst': Waiting for transfers to finish 2025/10/31 02:32:42 DEBUG : one: Dst hash empty - aborting Src hash check 2025/10/31 02:32:42 INFO : one: Copied (new) 2025/10/31 02:32:42 DEBUG : Waiting for deletions to finish 2025/10/31 02:32:42 INFO : three.txt.bak: Deleted 2025/10/31 02:32:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/10/31 02:32:43 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (14.50s) === RUN TestSyncSuffixKeepExtension run.go:185: Remote "files root 'rclone-test-loqujit3paxu'", Local "Local file system at /tmp/rclone882650323", Modify Window "1s" 2025/10/31 02:32:49 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-loqujit3paxu/dst" 2025/10/31 02:32:50 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/10/31 02:32:50 INFO : one: Moved (server-side) to: one-2019-01-01 2025/10/31 02:32:51 DEBUG : one: Dst hash empty - aborting Src hash check 2025/10/31 02:32:51 INFO : one: Copied (new) 2025/10/31 02:32:51 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/31 02:32:51 DEBUG : two: Unchanged skipping 2025/10/31 02:32:52 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/10/31 02:32:52 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/10/31 02:32:53 DEBUG : three.txt: Dst hash empty - aborting Src hash check 2025/10/31 02:32:53 INFO : three.txt: Copied (new) 2025/10/31 02:32:54 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/10/31 02:32:55 INFO : one-2019-01-01: Deleted 2025/10/31 02:32:55 INFO : one: Moved (server-side) to: one-2019-01-01 2025/10/31 02:32:59 DEBUG : one: Dst hash empty - aborting Src hash check 2025/10/31 02:32:59 INFO : one: Copied (new) 2025/10/31 02:32:59 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/31 02:32:59 DEBUG : two: Unchanged skipping 2025/10/31 02:32:59 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/10/31 02:33:00 INFO : three-2019-01-01.txt: Deleted 2025/10/31 02:33:00 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/10/31 02:33:01 DEBUG : three.txt: Dst hash empty - aborting Src hash check 2025/10/31 02:33:01 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (18.46s) === RUN TestFixCase run.go:185: Remote "files root 'rclone-test-loqujit3paxu'", Local "Local file system at /tmp/rclone882650323", Modify Window "1s" 2025/10/31 02:33:09 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/10/31 02:33:09 DEBUG : existing: Unchanged skipping 2025/10/31 02:33:09 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8) 2025/10/31 02:33:09 DEBUG : EXISTING: moving to existing-rclone-move-qavoyaw2 2025/10/31 02:33:09 INFO : SUBDIRA: Set directory modification time (using DirSetModTime) 2025/10/31 02:33:09 DEBUG : Added delayed dir = "SUBDIRA", newDst=SUBDIRA 2025/10/31 02:33:09 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-xayacaf7 2025/10/31 02:33:10 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-xayacaf7 2025/10/31 02:33:10 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent 2025/10/31 02:33:10 INFO : EXISTING: Moved (server-side) to: existing-rclone-move-qavoyaw2 2025/10/31 02:33:10 DEBUG : EXISTING: moving to existing 2025/10/31 02:33:10 INFO : existingbutdifferent-rclone-move-xayacaf7: Moved (server-side) to: existingbutdifferent 2025/10/31 02:33:10 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent 2025/10/31 02:33:10 INFO : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent 2025/10/31 02:33:10 INFO : existing-rclone-move-qavoyaw2: Moved (server-side) to: existing 2025/10/31 02:33:10 INFO : EXISTING: Moved (server-side) to: existing 2025/10/31 02:33:10 INFO : EXISTING: Fixed case by renaming to: existing 2025/10/31 02:33:10 INFO : SUBDIRA: Fixed case by renaming to: subdira 2025/10/31 02:33:10 INFO : SUBDIRA/subdirb: Set directory modification time (using DirSetModTime) 2025/10/31 02:33:10 DEBUG : Added delayed dir = "SUBDIRA/subdirb", newDst=SUBDIRA/subdirb 2025/10/31 02:33:11 DEBUG : existingbutdifferent: Dst hash empty - aborting Src hash check 2025/10/31 02:33:11 INFO : existingbutdifferent: Copied (replaced existing) 2025/10/31 02:33:12 INFO : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb 2025/10/31 02:33:12 INFO : SUBDIRA/subdirb/SUBDIRC: Set directory modification time (using DirSetModTime) 2025/10/31 02:33:12 DEBUG : Added delayed dir = "SUBDIRA/subdirb/SUBDIRC", newDst=SUBDIRA/subdirb/SUBDIRC 2025/10/31 02:33:13 INFO : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc 2025/10/31 02:33:13 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ (src 5 vs dst 8) 2025/10/31 02:33:13 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-qabixoy9 2025/10/31 02:33:13 INFO : SUBDIRA/subdirb/SUBDIRC/subdird: Set directory modification time (using DirSetModTime) 2025/10/31 02:33:13 DEBUG : Added delayed dir = "SUBDIRA/subdirb/SUBDIRC/subdird", newDst=SUBDIRA/subdirb/SUBDIRC/subdird 2025/10/31 02:33:14 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-qabixoy9 2025/10/31 02:33:14 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello 2025/10/31 02:33:14 INFO : subdira/subdirb/subdirc/hello-rclone-move-qabixoy9: Moved (server-side) to: subdira/subdirb/subdirc/hello 2025/10/31 02:33:14 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello 2025/10/31 02:33:14 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello 2025/10/31 02:33:15 INFO : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird 2025/10/31 02:33:15 DEBUG : files root 'rclone-test-loqujit3paxu': Waiting for checks to finish 2025/10/31 02:33:15 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ (src 5 vs dst 8) 2025/10/31 02:33:15 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-jowoxir7 2025/10/31 02:33:15 DEBUG : subdira/subdirb/subdirc/hello: Dst hash empty - aborting Src hash check 2025/10/31 02:33:15 INFO : subdira/subdirb/subdirc/hello: Copied (replaced existing) 2025/10/31 02:33:15 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-jowoxir7 2025/10/31 02:33:15 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/10/31 02:33:16 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-jowoxir7: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/10/31 02:33:16 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/10/31 02:33:16 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Fixed case by renaming to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/10/31 02:33:16 DEBUG : files root 'rclone-test-loqujit3paxu': Waiting for transfers to finish 2025/10/31 02:33:17 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Dst hash empty - aborting Src hash check 2025/10/31 02:33:17 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing) 2025/10/31 02:33:17 DEBUG : Waiting for deletions to finish 2025/10/31 02:33:19 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-loqujit3paxu/subdira/subdirb/subdirc not empty`) 2025/10/31 02:33:19 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/10/31 02:33:19 DEBUG : pacer: Reducing sleep to 15ms 2025/10/31 02:33:19 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-loqujit3paxu/subdira/subdirb not empty`) 2025/10/31 02:33:19 DEBUG : pacer: Rate limited, increasing sleep to 30ms 2025/10/31 02:33:19 DEBUG : pacer: Reducing sleep to 22.5ms 2025/10/31 02:33:20 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-loqujit3paxu/subdira not empty`) 2025/10/31 02:33:20 DEBUG : pacer: Rate limited, increasing sleep to 45ms 2025/10/31 02:33:20 DEBUG : pacer: Reducing sleep to 33.75ms 2025/10/31 02:33:20 DEBUG : pacer: Reducing sleep to 25.3125ms --- PASS: TestFixCase (15.50s) === RUN TestNoTag run.go:185: Remote "files root 'rclone-test-loqujit3paxu'", Local "Local file system at /tmp/rclone882650323", Modify Window "1s" 2025/10/31 02:33:20 DEBUG : pacer: Reducing sleep to 18.984375ms 2025/10/31 02:33:20 INFO : toe: Making directory 2025/10/31 02:33:20 DEBUG : pacer: Reducing sleep to 14.238281ms 2025/10/31 02:33:20 DEBUG : pacer: Reducing sleep to 10.67871ms 2025/10/31 02:33:20 INFO : toe: Made directory with modification time 2025-10-31 02:33:20.420774991 +0000 UTC 2025/10/31 02:33:20 DEBUG : Added delayed dir = "toe", newDst= 2025/10/31 02:33:20 INFO : toe/toe: Making directory 2025/10/31 02:33:21 DEBUG : pacer: Reducing sleep to 10ms 2025/10/31 02:33:21 INFO : toe/toe: Made directory with modification time 2025-10-31 02:33:20.420774991 +0000 UTC 2025/10/31 02:33:21 DEBUG : Added delayed dir = "toe/toe", newDst= 2025/10/31 02:33:21 DEBUG : toe: transformed to: tictactoe 2025/10/31 02:33:21 DEBUG : toe/toe/toe: transformed to: toe/toe/tictactoe 2025/10/31 02:33:21 DEBUG : toe/toe/toe: Need to transfer - File not found at Destination 2025/10/31 02:33:21 DEBUG : toe/toe/toe: transformed to: toe/toe/tictactoe 2025/10/31 02:33:21 DEBUG : files root 'rclone-test-loqujit3paxu': Waiting for checks to finish 2025/10/31 02:33:21 DEBUG : files root 'rclone-test-loqujit3paxu': Waiting for transfers to finish 2025/10/31 02:33:21 DEBUG : toe/toe/tictactoe: Dst hash empty - aborting Src hash check 2025/10/31 02:33:21 INFO : toe/toe/toe: Copied (new) to: toe/toe/tictactoe 2025/10/31 02:33:21 DEBUG : Waiting for deletions to finish 2025/10/31 02:33:22 INFO : toe/toe: Set directory modification time (using DirSetModTime) 2025/10/31 02:33:22 INFO : toe: Set directory modification time (using DirSetModTime) 2025/10/31 02:33:23 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-loqujit3paxu/toe not empty`) 2025/10/31 02:33:23 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/10/31 02:33:23 DEBUG : pacer: Reducing sleep to 15ms 2025/10/31 02:33:23 DEBUG : pacer: Reducing sleep to 11.25ms --- PASS: TestNoTag (3.32s) FAIL 2025/10/31 02:33:23 DEBUG : files root 'rclone-test-loqujit3paxu': Purge remote 2025/10/31 02:33:23 DEBUG : pacer: Reducing sleep to 10ms "./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyRedownload|TestFixCase|TestNoTag|TestServerSideMoveOverSelf|TestSyncBackupDirSuffixOnly|TestSyncMultipleCompareDest|TestSyncSuffixKeepExtension)$'" - Finished ERROR in 1m43.505996799s (try 2/5): exit status 1: Failed [TestServerSideMoveOverSelf]