"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestNothingToTransferWithEmptyDirs|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix)$'" - Starting (try 2/5) 2025/12/28 03:36:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-defokup3cele" 2025/12/28 03:36:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/28 03:36:17 DEBUG : Starting OpenDrive session with ID: a833bdc96894e7796b9fdd62644890bcfdedf219e3e692b1c938fa00a418ce55 2025/12/28 03:36:17 DEBUG : Creating backend with remote "/tmp/rclone509348271" === RUN TestServerSideCopyOverSelf run.go:185: Remote "OpenDrive root 'rclone-test-defokup3cele'", Local "Local file system at /tmp/rclone509348271", Modify Window "1s" 2025/12/28 03:36:19 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/12/28 03:36:20 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-yopumaw1wofi" 2025/12/28 03:36:21 DEBUG : Starting OpenDrive session with ID: c799f61dd0e8d504fb23c17668a60ffa34e866e7750b4dbec63dff49401b84d2 sync_test.go:656: Server side copy (if possible) OpenDrive root 'rclone-test-defokup3cele' -> OpenDrive root 'rclone-test-yopumaw1wofi' 2025/12/28 03:36:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/28 03:36:21 DEBUG : OpenDrive root 'rclone-test-yopumaw1wofi': Waiting for checks to finish 2025/12/28 03:36:21 DEBUG : OpenDrive root 'rclone-test-yopumaw1wofi': Waiting for transfers to finish 2025/12/28 03:36:22 DEBUG : sub dir/hello world: size = 11 OK 2025/12/28 03:36:22 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/12/28 03:36:22 INFO : sub dir/hello world: Copied (server-side copy) 2025/12/28 03:36:24 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/12/28 03:36:25 DEBUG : sub dir/hello world: size = 17 (OpenDrive root 'rclone-test-defokup3cele') 2025/12/28 03:36:25 DEBUG : sub dir/hello world: size = 11 (OpenDrive root 'rclone-test-yopumaw1wofi') 2025/12/28 03:36:25 DEBUG : OpenDrive root 'rclone-test-yopumaw1wofi': Waiting for checks to finish 2025/12/28 03:36:25 DEBUG : sub dir/hello world: Sizes differ 2025/12/28 03:36:25 DEBUG : OpenDrive root 'rclone-test-yopumaw1wofi': Waiting for transfers to finish 2025/12/28 03:36:26 DEBUG : sub dir/hello world: size = 17 OK 2025/12/28 03:36:26 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/12/28 03:36:26 INFO : sub dir/hello world: Copied (server-side copy) 2025/12/28 03:36:26 DEBUG : OpenDrive root 'rclone-test-yopumaw1wofi': Purge remote --- PASS: TestServerSideCopyOverSelf (10.92s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "OpenDrive root 'rclone-test-defokup3cele'", Local "Local file system at /tmp/rclone509348271", Modify Window "1s" 2025/12/28 03:36:29 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/12/28 03:36:31 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-leneliz9nezo" 2025/12/28 03:36:31 DEBUG : Starting OpenDrive session with ID: 11e6e824a5da1d11d16913ec0705d5d82073204f024bcd93f71a7bd26e672249 sync_test.go:701: Server side copy (if possible) OpenDrive root 'rclone-test-defokup3cele' -> OpenDrive root 'rclone-test-leneliz9nezo' 2025/12/28 03:36:32 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/28 03:36:32 DEBUG : OpenDrive root 'rclone-test-leneliz9nezo': Waiting for checks to finish 2025/12/28 03:36:32 DEBUG : OpenDrive root 'rclone-test-leneliz9nezo': Waiting for transfers to finish 2025/12/28 03:36:33 DEBUG : sub dir/hello world: size = 11 OK 2025/12/28 03:36:33 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/12/28 03:36:33 INFO : sub dir/hello world: Copied (server-side copy) 2025/12/28 03:36:35 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/12/28 03:36:36 DEBUG : OpenDrive root 'rclone-test-leneliz9nezo': Using server-side directory move 2025/12/28 03:36:36 INFO : OpenDrive root 'rclone-test-leneliz9nezo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/12/28 03:36:36 DEBUG : sub dir/hello world: size = 17 (OpenDrive root 'rclone-test-defokup3cele') 2025/12/28 03:36:36 DEBUG : sub dir/hello world: size = 11 (OpenDrive root 'rclone-test-leneliz9nezo') 2025/12/28 03:36:36 DEBUG : sub dir/hello world: Sizes differ 2025/12/28 03:36:36 DEBUG : OpenDrive root 'rclone-test-leneliz9nezo': Waiting for checks to finish 2025/12/28 03:36:36 DEBUG : OpenDrive root 'rclone-test-leneliz9nezo': Waiting for transfers to finish 2025/12/28 03:36:37 INFO : sub dir/hello world: Deleted 2025/12/28 03:36:37 INFO : sub dir/hello world: Moved (server-side) 2025/12/28 03:36:39 DEBUG : sub dir/hello world: Uploading chunk 0, size=24, remain=0 2025/12/28 03:36:40 DEBUG : testing file moves 2025/12/28 03:36:40 DEBUG : OpenDrive root 'rclone-test-leneliz9nezo': Waiting for checks to finish 2025/12/28 03:36:40 DEBUG : sub dir/hello world: size = 24 (OpenDrive root 'rclone-test-defokup3cele') 2025/12/28 03:36:40 DEBUG : sub dir/hello world: size = 17 (OpenDrive root 'rclone-test-leneliz9nezo') 2025/12/28 03:36:40 DEBUG : sub dir/hello world: Sizes differ 2025/12/28 03:36:40 DEBUG : OpenDrive root 'rclone-test-leneliz9nezo': Waiting for transfers to finish 2025/12/28 03:36:41 INFO : sub dir/hello world: Deleted 2025/12/28 03:36:41 INFO : sub dir/hello world: Moved (server-side) 2025/12/28 03:36:42 DEBUG : OpenDrive root 'rclone-test-leneliz9nezo': Purge remote --- PASS: TestServerSideMoveOverSelf (15.52s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:185: Remote "OpenDrive root 'rclone-test-defokup3cele'", Local "Local file system at /tmp/rclone509348271", Modify Window "1s" 2025/12/28 03:36:45 DEBUG : b/potato: Uploading chunk 0, size=21, remain=0 2025/12/28 03:36:47 DEBUG : c/non empty space: Uploading chunk 0, size=5, remain=0 2025/12/28 03:36:48 INFO : d: Making directory 2025/12/28 03:36:48 INFO : d/e: Making directory 2025/12/28 03:36:50 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/12/28 03:36:50 DEBUG : c/non empty space: size = 5 OK 2025/12/28 03:36:50 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/12/28 03:36:50 DEBUG : c/non empty space: Unchanged skipping 2025/12/28 03:36:50 DEBUG : OpenDrive root 'rclone-test-defokup3cele': Waiting for checks to finish 2025/12/28 03:36:50 DEBUG : OpenDrive root 'rclone-test-defokup3cele': Waiting for transfers to finish 2025/12/28 03:36:51 DEBUG : a/potato2: Uploading chunk 0, size=60, remain=0 2025/12/28 03:36:52 DEBUG : a/potato2: size = 60 OK 2025/12/28 03:36:52 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/12/28 03:36:52 INFO : a/potato2: Copied (new) 2025/12/28 03:36:52 DEBUG : Waiting for deletions to finish 2025/12/28 03:36:52 INFO : b/potato: Deleted 2025/12/28 03:36:52 INFO : d/e: Removing directory 2025/12/28 03:36:53 INFO : d: Removing directory 2025/12/28 03:36:53 INFO : b: Removing directory 2025/12/28 03:36:53 DEBUG : OpenDrive root 'rclone-test-defokup3cele': deleted 3 directories --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (11.89s) === RUN TestSyncBackupDirWithSuffix run.go:185: Remote "OpenDrive root 'rclone-test-defokup3cele'", Local "Local file system at /tmp/rclone509348271", Modify Window "1s" 2025/12/28 03:36:56 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/12/28 03:36:58 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/12/28 03:37:00 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/12/28 03:37:01 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-defokup3cele/dst" 2025/12/28 03:37:02 DEBUG : Starting OpenDrive session with ID: 4d3ade17a41d4371062242de830a128d4aab1fed6a843125bb660804a7d86c47 2025/12/28 03:37:02 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-defokup3cele/backup" 2025/12/28 03:37:03 DEBUG : Starting OpenDrive session with ID: 16a8caf2a281cbd76003772ca0bd40df1eab600c4f32bcf75c770bc1249660c4 2025/12/28 03:37:03 DEBUG : one: size = 4 (Local file system at /tmp/rclone509348271) 2025/12/28 03:37:03 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-defokup3cele/dst') 2025/12/28 03:37:03 DEBUG : one: Sizes differ 2025/12/28 03:37:03 DEBUG : two: size = 3 OK 2025/12/28 03:37:03 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/12/28 03:37:03 DEBUG : two: Unchanged skipping 2025/12/28 03:37:03 DEBUG : OpenDrive root 'rclone-test-defokup3cele/dst': Waiting for checks to finish 2025/12/28 03:37:04 INFO : one: Moved (server-side) to: one.bak 2025/12/28 03:37:04 DEBUG : OpenDrive root 'rclone-test-defokup3cele/dst': Waiting for transfers to finish 2025/12/28 03:37:05 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/12/28 03:37:06 DEBUG : one: size = 4 OK 2025/12/28 03:37:06 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/12/28 03:37:06 INFO : one: Copied (new) 2025/12/28 03:37:06 DEBUG : Waiting for deletions to finish 2025/12/28 03:37:07 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/12/28 03:37:07 INFO : three.txt: Moved into backup dir 2025/12/28 03:37:08 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/12/28 03:37:10 DEBUG : one: size = 5 (Local file system at /tmp/rclone509348271) 2025/12/28 03:37:10 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-defokup3cele/dst') 2025/12/28 03:37:10 DEBUG : one: Sizes differ 2025/12/28 03:37:10 DEBUG : OpenDrive root 'rclone-test-defokup3cele/dst': Waiting for checks to finish 2025/12/28 03:37:10 DEBUG : two: size = 3 OK 2025/12/28 03:37:10 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/12/28 03:37:10 DEBUG : two: Unchanged skipping 2025/12/28 03:37:10 INFO : one.bak: Deleted 2025/12/28 03:37:10 INFO : one: Moved (server-side) to: one.bak 2025/12/28 03:37:10 DEBUG : OpenDrive root 'rclone-test-defokup3cele/dst': Waiting for transfers to finish 2025/12/28 03:37:11 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/12/28 03:37:12 DEBUG : one: size = 5 OK 2025/12/28 03:37:12 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/12/28 03:37:12 INFO : one: Copied (new) 2025/12/28 03:37:12 DEBUG : Waiting for deletions to finish 2025/12/28 03:37:12 INFO : three.txt.bak: Deleted 2025/12/28 03:37:13 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/12/28 03:37:13 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (19.67s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "OpenDrive root 'rclone-test-defokup3cele'", Local "Local file system at /tmp/rclone509348271", Modify Window "1s" 2025/12/28 03:37:16 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/12/28 03:37:18 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/12/28 03:37:20 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/12/28 03:37:21 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-defokup3cele/dst" 2025/12/28 03:37:21 DEBUG : Starting OpenDrive session with ID: 75403128097f7563235dc4d20e0502a8a5aff539412694185a6e0bd9b0491fd7 2025/12/28 03:37:22 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-defokup3cele/backup" 2025/12/28 03:37:22 DEBUG : Starting OpenDrive session with ID: 7e774a5246121d71f42a51974cb5c3f1789483604441303f2cfeb9121cec97ad 2025/12/28 03:37:23 DEBUG : one: size = 4 (Local file system at /tmp/rclone509348271) 2025/12/28 03:37:23 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-defokup3cele/dst') 2025/12/28 03:37:23 DEBUG : one: Sizes differ 2025/12/28 03:37:23 DEBUG : two: size = 3 OK 2025/12/28 03:37:23 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/12/28 03:37:23 DEBUG : two: Unchanged skipping 2025/12/28 03:37:23 DEBUG : OpenDrive root 'rclone-test-defokup3cele/dst': Waiting for checks to finish 2025/12/28 03:37:24 INFO : one: Moved (server-side) to: one-2019-01-01 2025/12/28 03:37:24 DEBUG : OpenDrive root 'rclone-test-defokup3cele/dst': Waiting for transfers to finish 2025/12/28 03:37:25 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/12/28 03:37:26 DEBUG : one: size = 4 OK 2025/12/28 03:37:26 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/12/28 03:37:26 INFO : one: Copied (new) 2025/12/28 03:37:26 DEBUG : Waiting for deletions to finish 2025/12/28 03:37:26 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/12/28 03:37:26 INFO : three.txt: Moved into backup dir 2025/12/28 03:37:28 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/12/28 03:37:29 DEBUG : one: size = 5 (Local file system at /tmp/rclone509348271) 2025/12/28 03:37:29 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-defokup3cele/dst') 2025/12/28 03:37:29 DEBUG : one: Sizes differ 2025/12/28 03:37:29 DEBUG : two: size = 3 OK 2025/12/28 03:37:29 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/12/28 03:37:29 DEBUG : OpenDrive root 'rclone-test-defokup3cele/dst': Waiting for checks to finish 2025/12/28 03:37:29 DEBUG : two: Unchanged skipping 2025/12/28 03:37:30 INFO : one-2019-01-01: Deleted 2025/12/28 03:37:30 INFO : one: Moved (server-side) to: one-2019-01-01 2025/12/28 03:37:30 DEBUG : OpenDrive root 'rclone-test-defokup3cele/dst': Waiting for transfers to finish 2025/12/28 03:37:30 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/12/28 03:37:31 DEBUG : one: size = 5 OK 2025/12/28 03:37:31 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/12/28 03:37:31 INFO : one: Copied (new) 2025/12/28 03:37:31 DEBUG : Waiting for deletions to finish 2025/12/28 03:37:32 INFO : three-2019-01-01.txt: Deleted 2025/12/28 03:37:32 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/12/28 03:37:32 INFO : three.txt: Moved into backup dir fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache 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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (4), backup/three-2019-01-01.txt (6), dst/one (5), dst/two (3) got backup/one-2019-01-01 (5), backup/three-2019-01-01.txt (6), dst/two (3) 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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: OpenDrive root 'rclone-test-defokup3cele'/backup/one-2019-01-01: md5 hash incorrect - expecting "c7957179c41f69d44f217a108c7915d8" got "0f93e81041f0cab37c37a05ae998b219" 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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Not equal: expected: 4 actual : 5 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/one-2019-01-01: size incorrect file=4 vs obj=5 fstest.go:127: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:132 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:151 /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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/one-2019-01-01: Modification time difference too big |-119h59m59.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2011-12-30 12:59:59 +0000 UTC) (precision 1s) fstest.go:203: Not found "dst/one" 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:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 1 objects not found --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (27.37s) === RUN TestSyncSuffix run.go:185: Remote "OpenDrive root 'rclone-test-defokup3cele'", Local "Local file system at /tmp/rclone509348271", Modify Window "1s" 2025/12/28 03:37:43 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/12/28 03:37:45 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/12/28 03:37:47 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/12/28 03:37:48 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-defokup3cele/dst" 2025/12/28 03:37:48 DEBUG : Starting OpenDrive session with ID: b2901ba53e6635c8fd43fcf5c8761ee487a195974235d4f57fd1787501f0d22f 2025/12/28 03:37:49 DEBUG : one: size = 4 (Local file system at /tmp/rclone509348271) 2025/12/28 03:37:49 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-defokup3cele/dst') 2025/12/28 03:37:49 DEBUG : one: Sizes differ 2025/12/28 03:37:49 DEBUG : one: same parent dir (ODFfOTY1NTA2Nl9za21iVg) - using file/rename instead of move_copy for one.bak 2025/12/28 03:37:50 INFO : one: Moved (server-side) to: one.bak 2025/12/28 03:37:50 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/12/28 03:37:51 DEBUG : one: size = 4 OK 2025/12/28 03:37:51 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/12/28 03:37:51 INFO : one: Copied (new) 2025/12/28 03:37:52 DEBUG : two: size = 3 OK 2025/12/28 03:37:52 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/12/28 03:37:52 DEBUG : two: Unchanged skipping 2025/12/28 03:37:52 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone509348271) 2025/12/28 03:37:52 DEBUG : three.txt: size = 5 (OpenDrive root 'rclone-test-defokup3cele/dst') 2025/12/28 03:37:52 DEBUG : three.txt: Sizes differ 2025/12/28 03:37:52 DEBUG : three.txt: same parent dir (ODFfOTY1NTA2Nl9za21iVg) - using file/rename instead of move_copy for three.txt.bak 2025/12/28 03:37:52 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/12/28 03:37:53 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2025/12/28 03:37:54 DEBUG : three.txt: size = 6 OK 2025/12/28 03:37:54 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/12/28 03:37:54 INFO : three.txt: Copied (new) 2025/12/28 03:37:55 DEBUG : one: size = 5 (Local file system at /tmp/rclone509348271) 2025/12/28 03:37:55 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-defokup3cele/dst') 2025/12/28 03:37:55 DEBUG : one: Sizes differ 2025/12/28 03:37:55 INFO : one.bak: Deleted 2025/12/28 03:37:55 DEBUG : one: same parent dir (ODFfOTY1NTA2Nl9za21iVg) - using file/rename instead of move_copy for one.bak 2025/12/28 03:37:56 INFO : one: Moved (server-side) to: one.bak 2025/12/28 03:37:56 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/12/28 03:37:57 DEBUG : one: size = 5 OK 2025/12/28 03:37:57 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/12/28 03:37:57 INFO : one: Copied (new) 2025/12/28 03:37:58 DEBUG : two: size = 3 OK 2025/12/28 03:37:58 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/12/28 03:37:58 DEBUG : two: Unchanged skipping 2025/12/28 03:37:58 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone509348271) 2025/12/28 03:37:58 DEBUG : three.txt: size = 6 (OpenDrive root 'rclone-test-defokup3cele/dst') 2025/12/28 03:37:58 DEBUG : three.txt: Sizes differ 2025/12/28 03:37:58 INFO : three.txt.bak: Deleted 2025/12/28 03:37:58 DEBUG : three.txt: same parent dir (ODFfOTY1NTA2Nl9za21iVg) - using file/rename instead of move_copy for three.txt.bak 2025/12/28 03:37:58 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/12/28 03:37:59 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2025/12/28 03:38:00 DEBUG : three.txt: size = 19 OK 2025/12/28 03:38:00 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/12/28 03:38:00 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (19.76s) === RUN TestNothingToTransferWithEmptyDirs run.go:185: Remote "OpenDrive root 'rclone-test-defokup3cele'", Local "Local file system at /tmp/rclone509348271", Modify Window "1s" 2025/12/28 03:38:02 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/12/28 03:38:02 INFO : sub dir: Making directory 2025/12/28 03:38:03 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC sync_test.go:2899: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2899 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bilib/output.go:26 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2897 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestNothingToTransferWithEmptyDirs --- FAIL: TestNothingToTransferWithEmptyDirs (7.54s) FAIL 2025/12/28 03:38:10 DEBUG : OpenDrive root 'rclone-test-defokup3cele': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestNothingToTransferWithEmptyDirs|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix)$'" - Finished ERROR in 1m54.048623285s (try 2/5): exit status 1: Failed [TestSyncBackupDirWithSuffixKeepExtension TestNothingToTransferWithEmptyDirs]