"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopy|TestCopyMetadata|TestCopyMissingDirectory|TestCopyWithDryRun|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncBackupDir|TestSyncOverlapWithFilter)$'" - Starting (try 2/5) 2025/08/29 03:49:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-kevequg7xata" 2025/08/29 03:49:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/29 03:49:17 DEBUG : Starting OpenDrive session with ID: 65b8ebe67a5b47535008fb6e6584cbbf23c56d7ca38b233a1502f5705eb4ac60 2025/08/29 03:49:17 DEBUG : Creating backend with remote "/tmp/rclone2438181282" === RUN TestCopyWithDryRun run.go:180: Remote "OpenDrive root 'rclone-test-kevequg7xata'", Local "Local file system at /tmp/rclone2438181282", Modify Window "1s" 2025/08/29 03:49:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/29 03:49:17 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/08/29 03:49:17 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Waiting for checks to finish 2025/08/29 03:49:17 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.13s) === RUN TestCopy run.go:180: Remote "OpenDrive root 'rclone-test-kevequg7xata'", Local "Local file system at /tmp/rclone2438181282", Modify Window "1s" 2025/08/29 03:49:18 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/08/29 03:49:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/29 03:49:18 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Waiting for checks to finish 2025/08/29 03:49:18 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Waiting for transfers to finish 2025/08/29 03:49:19 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/08/29 03:49:20 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/08/29 03:49:20 INFO : sub dir/hello world: Copied (new) 2025/08/29 03:49:21 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (3.74s) === RUN TestCopyMetadata run.go:180: Remote "OpenDrive root 'rclone-test-kevequg7xata'", Local "Local file system at /tmp/rclone2438181282", Modify Window "1s" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.36s) === RUN TestCopyMissingDirectory run.go:180: Remote "OpenDrive root 'rclone-test-kevequg7xata'", Local "Local file system at /tmp/rclone2438181282", Modify Window "1s" 2025/08/29 03:49:22 DEBUG : Creating backend with remote "/non-existing" 2025/08/29 03:49:22 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/08/29 03:49:22 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Waiting for checks to finish 2025/08/29 03:49:22 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.54s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "OpenDrive root 'rclone-test-kevequg7xata'", Local "Local file system at /tmp/rclone2438181282", Modify Window "1s" 2025/08/29 03:49:24 DEBUG : b/potato: Uploading chunk 0, size=21, remain=0 2025/08/29 03:49:26 DEBUG : c/non empty space: Uploading chunk 0, size=5, remain=0 2025/08/29 03:49:27 INFO : d: Making directory 2025/08/29 03:49:27 INFO : d/e: Making directory 2025/08/29 03:49:29 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/08/29 03:49:29 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/08/29 03:49:29 DEBUG : c/non empty space: Unchanged skipping 2025/08/29 03:49:29 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Waiting for checks to finish 2025/08/29 03:49:29 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Waiting for transfers to finish 2025/08/29 03:49:30 DEBUG : a/potato2: Uploading chunk 0, size=60, remain=0 2025/08/29 03:49:31 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/08/29 03:49:31 INFO : a/potato2: Copied (new) 2025/08/29 03:49:31 DEBUG : Waiting for deletions to finish 2025/08/29 03:49:31 INFO : b/potato: Deleted 2025/08/29 03:49:31 INFO : d/e: Removing directory 2025/08/29 03:49:32 INFO : d: Removing directory 2025/08/29 03:49:32 INFO : b: Removing directory 2025/08/29 03:49:32 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': deleted 3 directories --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (11.87s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "OpenDrive root 'rclone-test-kevequg7xata'", Local "Local file system at /tmp/rclone2438181282", Modify Window "1s" 2025/08/29 03:49:35 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-kevequg7xata/rclone-sync-test" 2025/08/29 03:49:35 DEBUG : Starting OpenDrive session with ID: 8cafee9a20d07fbf196877f171fc91fe2766b390653a5ded660026aec99395e6 2025/08/29 03:49:36 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-kevequg7xata/rclone-sync-test-include/layer2" 2025/08/29 03:49:37 DEBUG : Starting OpenDrive session with ID: 49bae34e4d7294379ab61d322101ca69f88f5290733ebff52b85ee1ba80a4087 2025/08/29 03:49:38 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-kevequg7xata/rclone-sync-test-ignore-file" 2025/08/29 03:49:39 DEBUG : Starting OpenDrive session with ID: c9811667cec1f285b8f5685cecc2236833a3c455286a0ccfcfc0505301b8d31a 2025/08/29 03:49:41 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0 2025/08/29 03:49:43 DEBUG : rclone-sync-test: Excluded 2025/08/29 03:49:43 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/08/29 03:49:43 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/08/29 03:49:43 DEBUG : OpenDrive root 'rclone-test-kevequg7xata/rclone-sync-test': Waiting for checks to finish 2025/08/29 03:49:43 DEBUG : OpenDrive root 'rclone-test-kevequg7xata/rclone-sync-test': Waiting for transfers to finish 2025/08/29 03:49:43 DEBUG : Waiting for deletions to finish 2025/08/29 03:49:43 INFO : There was nothing to transfer 2025/08/29 03:49:44 DEBUG : rclone-sync-test: Excluded 2025/08/29 03:49:44 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/08/29 03:49:45 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/08/29 03:49:45 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Waiting for checks to finish 2025/08/29 03:49:45 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Waiting for transfers to finish 2025/08/29 03:49:45 DEBUG : Waiting for deletions to finish 2025/08/29 03:49:45 INFO : rclone-sync-test-include: Removing directory 2025/08/29 03:49:45 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': deleted 1 directories 2025/08/29 03:49:45 INFO : There was nothing to transfer 2025/08/29 03:49:46 DEBUG : rclone-sync-test: Excluded 2025/08/29 03:49:46 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/08/29 03:49:46 DEBUG : OpenDrive root 'rclone-test-kevequg7xata/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/08/29 03:49:46 DEBUG : OpenDrive root 'rclone-test-kevequg7xata/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/08/29 03:49:46 DEBUG : Waiting for deletions to finish 2025/08/29 03:49:46 INFO : There was nothing to transfer 2025/08/29 03:49:46 DEBUG : rclone-sync-test: Excluded 2025/08/29 03:49:47 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/08/29 03:49:47 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Waiting for checks to finish 2025/08/29 03:49:47 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Waiting for transfers to finish 2025/08/29 03:49:47 DEBUG : Waiting for deletions to finish 2025/08/29 03:49:47 INFO : There was nothing to transfer 2025/08/29 03:49:47 DEBUG : Excluded 2025/08/29 03:49:47 DEBUG : rclone-sync-test: Excluded 2025/08/29 03:49:47 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/08/29 03:49:47 DEBUG : OpenDrive root 'rclone-test-kevequg7xata/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/08/29 03:49:47 DEBUG : OpenDrive root 'rclone-test-kevequg7xata/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/08/29 03:49:47 DEBUG : Waiting for deletions to finish 2025/08/29 03:49:47 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (14.22s) === RUN TestSyncBackupDir run.go:180: Remote "OpenDrive root 'rclone-test-kevequg7xata'", Local "Local file system at /tmp/rclone2438181282", Modify Window "1s" 2025/08/29 03:49:50 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/08/29 03:49:52 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/08/29 03:49:53 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/08/29 03:49:55 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-kevequg7xata/dst" 2025/08/29 03:49:55 DEBUG : Config file has changed externally - reloading 2025/08/29 03:49:55 DEBUG : Starting OpenDrive session with ID: 0b00d20194b929b2d2093e0be319edd720ab6882fe77f50538ffb8537be73213 2025/08/29 03:49:56 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-kevequg7xata/backup" 2025/08/29 03:49:56 DEBUG : Starting OpenDrive session with ID: 8679a5f6226734a04acecd3f6878e3733203017f54bf666e61afe3b5069e9926 2025/08/29 03:49:57 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/29 03:49:57 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/29 03:49:57 DEBUG : two: Unchanged skipping 2025/08/29 03:49:57 DEBUG : OpenDrive root 'rclone-test-kevequg7xata/dst': Waiting for checks to finish 2025/08/29 03:49:58 INFO : one: Moved (server-side) 2025/08/29 03:49:58 DEBUG : OpenDrive root 'rclone-test-kevequg7xata/dst': Waiting for transfers to finish 2025/08/29 03:49:59 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/08/29 03:50:00 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/08/29 03:50:00 INFO : one: Copied (new) 2025/08/29 03:50:00 DEBUG : Waiting for deletions to finish 2025/08/29 03:50:01 INFO : three.txt: Moved (server-side) 2025/08/29 03:50:01 INFO : three.txt: Moved into backup dir 2025/08/29 03:50:02 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/08/29 03:50:04 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/08/29 03:50:04 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/29 03:50:04 DEBUG : two: Unchanged skipping 2025/08/29 03:50:04 DEBUG : OpenDrive root 'rclone-test-kevequg7xata/dst': Waiting for checks to finish 2025/08/29 03:50:04 INFO : one: Deleted 2025/08/29 03:50:05 INFO : one: Moved (server-side) 2025/08/29 03:50:05 DEBUG : OpenDrive root 'rclone-test-kevequg7xata/dst': Waiting for transfers to finish 2025/08/29 03:50:06 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/08/29 03:50:07 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/08/29 03:50:07 INFO : one: Copied (new) 2025/08/29 03:50:07 DEBUG : Waiting for deletions to finish 2025/08/29 03:50:07 INFO : three.txt: Deleted 2025/08/29 03:50:08 INFO : three.txt: Moved (server-side) 2025/08/29 03:50:08 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (21.43s) PASS 2025/08/29 03:50:10 DEBUG : OpenDrive root 'rclone-test-kevequg7xata': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopy|TestCopyMetadata|TestCopyMissingDirectory|TestCopyWithDryRun|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncBackupDir|TestSyncOverlapWithFilter)$'" - Finished OK in 54.676084626s (try 2/5)