"./sync.test -test.v -test.timeout 1h0m0s -remote TestZoho: -verbose" - Starting (try 1/5) 2022/04/09 05:06:18 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1" 2022/04/09 05:06:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/04/09 05:06:18 DEBUG : TestZoho: Loaded invalid token from config file - ignoring 2022/04/09 05:06:18 DEBUG : Saving config "token" in section "TestZoho" of the config file 2022/04/09 05:06:18 DEBUG : TestZoho: Saved new token in config file 2022/04/09 05:06:19 DEBUG : Creating backend with remote "/tmp/rclone4055992605" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:20 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2022/04/09 05:06:20 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:20 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (1.11s) === RUN TestCopy run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:20 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:20 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:21 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (2.32s) === RUN TestCopyMissingDirectory run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:22 DEBUG : Creating backend with remote "/non-existing" 2022/04/09 05:06:22 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/04/09 05:06:22 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:22 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.22s) === RUN TestCopyNoTraverse run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:23 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:23 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:24 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (2.38s) === RUN TestCopyCheckFirst run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:25 INFO : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Running all checks before starting transfers 2022/04/09 05:06:25 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:25 INFO : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Checks finished, now starting transfers 2022/04/09 05:06:25 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:26 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (2.14s) === RUN TestSyncNoTraverse run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:27 ERROR : Ignoring --no-traverse with sync 2022/04/09 05:06:27 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:27 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:28 INFO : sub dir/hello world: Copied (new) 2022/04/09 05:06:28 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (2.09s) === RUN TestCopyWithDepth run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:29 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:29 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:30 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.19s) === RUN TestCopyWithFilesFrom run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:30 DEBUG : hello world2: Excluded 2022/04/09 05:06:30 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:30 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:31 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (1.21s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:32 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:32 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:32 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (1.24s) === RUN TestCopyEmptyDirectories run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:33 DEBUG : sub dir2: Making directory 2022/04/09 05:06:33 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:33 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:34 INFO : sub dir/hello world: Copied (new) 2022/04/09 05:06:34 DEBUG : sub dir2: Making directory 2022/04/09 05:06:34 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': copied 1 directories --- PASS: TestCopyEmptyDirectories (2.62s) === RUN TestMoveEmptyDirectories run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:35 DEBUG : sub dir2: Making directory 2022/04/09 05:06:36 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:36 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:36 INFO : sub dir/hello world: Copied (new) 2022/04/09 05:06:36 INFO : sub dir/hello world: Deleted 2022/04/09 05:06:36 DEBUG : sub dir: Making directory 2022/04/09 05:06:36 DEBUG : sub dir2: Making directory 2022/04/09 05:06:37 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': copied 2 directories --- PASS: TestMoveEmptyDirectories (2.64s) === RUN TestSyncEmptyDirectories run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:38 DEBUG : sub dir2: Making directory 2022/04/09 05:06:38 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:38 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:39 INFO : sub dir/hello world: Copied (new) 2022/04/09 05:06:39 DEBUG : sub dir2: Making directory 2022/04/09 05:06:39 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': copied 1 directories 2022/04/09 05:06:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncEmptyDirectories (2.56s) === RUN TestServerSideCopy run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:42 DEBUG : Creating backend with remote "TestZoho:rclone-test-wisepov3xokafej7ganukez0" sync_test.go:275: Server side copy (if possible) zoho root 'rclone-test-vogovip3rixigan3kataxov1' -> zoho root 'rclone-test-wisepov3xokafej7ganukez0' 2022/04/09 05:06:43 DEBUG : zoho root 'rclone-test-wisepov3xokafej7ganukez0': Waiting for checks to finish 2022/04/09 05:06:43 DEBUG : zoho root 'rclone-test-wisepov3xokafej7ganukez0': Waiting for transfers to finish 2022/04/09 05:06:44 INFO : sub dir/hello world: Copied (server-side copy) 2022/04/09 05:06:45 DEBUG : zoho root 'rclone-test-wisepov3xokafej7ganukez0': Purge remote --- PASS: TestServerSideCopy (4.98s) === RUN TestCopyAfterDelete run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:47 ERROR : : error listing: directory not found 2022/04/09 05:06:48 DEBUG : Local file system at /tmp/rclone4055992605: Making directory 2022/04/09 05:06:48 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:48 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (3.48s) === RUN TestCopyRedownload run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:51 DEBUG : Local file system at /tmp/rclone4055992605: Waiting for checks to finish 2022/04/09 05:06:51 DEBUG : Local file system at /tmp/rclone4055992605: Waiting for transfers to finish 2022/04/09 05:06:51 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (2.33s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:52 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:52 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:52 INFO : check sum: Copied (new) 2022/04/09 05:06:52 DEBUG : Waiting for deletions to finish 2022/04/09 05:06:52 NOTICE: zoho root 'rclone-test-vogovip3rixigan3kataxov1': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2022/04/09 05:06:52 DEBUG : check sum: Size of src and dst objects identical 2022/04/09 05:06:52 DEBUG : check sum: Unchanged skipping 2022/04/09 05:06:52 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:52 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:52 DEBUG : Waiting for deletions to finish 2022/04/09 05:06:52 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.38s) === RUN TestSyncSizeOnly run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:53 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:53 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:53 INFO : sizeonly: Copied (new) 2022/04/09 05:06:53 DEBUG : Waiting for deletions to finish 2022/04/09 05:06:54 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:54 DEBUG : sizeonly: Sizes identical 2022/04/09 05:06:54 DEBUG : sizeonly: Unchanged skipping 2022/04/09 05:06:54 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:54 DEBUG : Waiting for deletions to finish 2022/04/09 05:06:54 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (1.39s) === RUN TestSyncIgnoreSize run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:06:54 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:54 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:55 INFO : ignore-size: Copied (new) 2022/04/09 05:06:55 DEBUG : Waiting for deletions to finish 2022/04/09 05:06:55 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:06:55 DEBUG : ignore-size: Sizes identical 2022/04/09 05:06:55 DEBUG : ignore-size: Unchanged skipping 2022/04/09 05:06:55 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:06:55 DEBUG : Waiting for deletions to finish 2022/04/09 05:06:55 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (1.55s) === RUN TestSyncIgnoreTimes run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Flushing the directory cache fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:429 Error: Should be true Test: TestSyncIgnoreTimes Messages: listing wrong, want existing (6) got existing (0) fstest.go:151: Error Trace: fstest.go:151 fstest.go:196 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:429 Error: Not equal: expected: 6 actual : 0 Test: TestSyncIgnoreTimes Messages: existing: size incorrect file=6 vs obj=0 2022/04/09 05:07:04 DEBUG : existing: Sizes differ (src 6 vs dst 0) 2022/04/09 05:07:04 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:04 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:05 INFO : existing: Copied (replaced existing) 2022/04/09 05:07:05 DEBUG : Waiting for deletions to finish sync_test.go:437: Error Trace: sync_test.go:437 Error: Not equal: expected: 0 actual : 1 Test: TestSyncIgnoreTimes 2022/04/09 05:07:05 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/04/09 05:07:05 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:05 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:05 INFO : existing: Copied (replaced existing) 2022/04/09 05:07:05 DEBUG : Waiting for deletions to finish --- FAIL: TestSyncIgnoreTimes (9.94s) === RUN TestSyncIgnoreExisting run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:06 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:06 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:06 INFO : existing: Copied (new) 2022/04/09 05:07:06 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:06 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:06 DEBUG : existing: Destination exists, skipping 2022/04/09 05:07:06 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:06 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:06 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (1.38s) === RUN TestSyncIgnoreErrors run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:09 DEBUG : d: Making directory 2022/04/09 05:07:10 DEBUG : c/non empty space: Sizes identical 2022/04/09 05:07:10 DEBUG : c/non empty space: Unchanged skipping 2022/04/09 05:07:11 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:11 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:11 INFO : a/potato2: Copied (new) 2022/04/09 05:07:11 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:11 INFO : b/potato: Deleted 2022/04/09 05:07:11 INFO : d: Removing directory 2022/04/09 05:07:11 INFO : b: Removing directory 2022/04/09 05:07:11 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': deleted 2 directories --- PASS: TestSyncIgnoreErrors (5.85s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:14 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:14 DEBUG : empty space: Sizes identical 2022/04/09 05:07:14 DEBUG : empty space: Unchanged skipping 2022/04/09 05:07:14 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:14 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:14 INFO : There was nothing to transfer 2022/04/09 05:07:14 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:14 DEBUG : empty space: Sizes identical 2022/04/09 05:07:14 DEBUG : empty space: Unchanged skipping 2022/04/09 05:07:14 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:14 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:14 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (1.78s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" sync_test.go:577: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.13s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" sync_test.go:602: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncDoesntUpdateModtime (0.13s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:16 DEBUG : empty space: Sizes identical 2022/04/09 05:07:16 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:16 DEBUG : empty space: Unchanged skipping 2022/04/09 05:07:16 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:16 INFO : potato: Copied (new) 2022/04/09 05:07:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (2.09s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:18 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/04/09 05:07:18 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:18 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:18 INFO : potato: Copied (replaced existing) 2022/04/09 05:07:18 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (1.57s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" sync_test.go:663: ModTimeNotSupported so forcing file to be a different size 2022/04/09 05:07:19 DEBUG : potato: Sizes differ (src 21 vs dst 36) 2022/04/09 05:07:19 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:19 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:20 INFO : potato: Copied (replaced existing) 2022/04/09 05:07:20 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (1.58s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:21 DEBUG : empty space: Sizes identical 2022/04/09 05:07:21 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/04/09 05:07:21 DEBUG : empty space: Unchanged skipping 2022/04/09 05:07:21 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:21 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:21 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:21 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.76s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:23 DEBUG : empty space: Sizes identical 2022/04/09 05:07:23 DEBUG : empty space: Unchanged skipping 2022/04/09 05:07:23 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:23 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:24 INFO : potato2: Copied (new) 2022/04/09 05:07:24 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:24 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (2.87s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:26 DEBUG : d: Making directory 2022/04/09 05:07:27 DEBUG : d/e: Making directory 2022/04/09 05:07:28 DEBUG : c/non empty space: Sizes identical 2022/04/09 05:07:28 DEBUG : c/non empty space: Unchanged skipping 2022/04/09 05:07:28 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:28 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:29 INFO : a/potato2: Copied (new) 2022/04/09 05:07:29 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:29 INFO : b/potato: Deleted 2022/04/09 05:07:29 INFO : d/e: Removing directory 2022/04/09 05:07:29 INFO : d: Removing directory 2022/04/09 05:07:29 INFO : b: Removing directory 2022/04/09 05:07:29 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': deleted 3 directories --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (6.19s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:33 DEBUG : d: Making directory 2022/04/09 05:07:34 DEBUG : c/non empty space: Sizes identical 2022/04/09 05:07:34 DEBUG : c/non empty space: Unchanged skipping 2022/04/09 05:07:34 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:34 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:34 INFO : a/potato2: Copied (new) 2022/04/09 05:07:34 ERROR : zoho root 'rclone-test-vogovip3rixigan3kataxov1': not deleting files as there were IO errors 2022/04/09 05:07:34 ERROR : zoho root 'rclone-test-vogovip3rixigan3kataxov1': not deleting directories as there were IO errors --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (5.64s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:38 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:38 DEBUG : empty space: Sizes identical 2022/04/09 05:07:38 DEBUG : empty space: Unchanged skipping 2022/04/09 05:07:38 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:38 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:38 INFO : potato: Deleted 2022/04/09 05:07:39 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (2.64s) === RUN TestSyncDeleteBefore run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:40 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:41 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:41 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:41 INFO : potato: Deleted 2022/04/09 05:07:41 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:41 DEBUG : empty space: Sizes identical 2022/04/09 05:07:41 DEBUG : empty space: Unchanged skipping 2022/04/09 05:07:41 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:42 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (2.98s) === RUN TestCopyDeleteBefore run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:43 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:43 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:44 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (1.99s) === RUN TestSyncWithExclude run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:46 DEBUG : enormous: Excluded 2022/04/09 05:07:46 DEBUG : potato2: Excluded 2022/04/09 05:07:46 DEBUG : potato2: Excluded 2022/04/09 05:07:46 DEBUG : empty space: Sizes identical 2022/04/09 05:07:46 DEBUG : empty space: Unchanged skipping 2022/04/09 05:07:46 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:46 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:46 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:46 INFO : There was nothing to transfer 2022/04/09 05:07:46 DEBUG : enormous: Excluded 2022/04/09 05:07:46 DEBUG : potato2: Excluded 2022/04/09 05:07:46 DEBUG : potato2: Excluded 2022/04/09 05:07:46 DEBUG : empty space: Sizes identical 2022/04/09 05:07:46 DEBUG : empty space: Unchanged skipping 2022/04/09 05:07:46 DEBUG : Local file system at /tmp/rclone4055992605: Waiting for checks to finish 2022/04/09 05:07:46 DEBUG : Local file system at /tmp/rclone4055992605: Waiting for transfers to finish 2022/04/09 05:07:46 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:46 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (2.22s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:48 DEBUG : enormous: Excluded 2022/04/09 05:07:48 DEBUG : potato2: Excluded 2022/04/09 05:07:49 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:49 DEBUG : empty space: Sizes identical 2022/04/09 05:07:49 DEBUG : empty space: Unchanged skipping 2022/04/09 05:07:49 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:49 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:49 INFO : enormous: Deleted 2022/04/09 05:07:49 INFO : potato2: Deleted 2022/04/09 05:07:49 INFO : There was nothing to transfer 2022/04/09 05:07:49 DEBUG : empty space: Sizes identical 2022/04/09 05:07:49 DEBUG : empty space: Unchanged skipping 2022/04/09 05:07:49 DEBUG : Local file system at /tmp/rclone4055992605: Waiting for checks to finish 2022/04/09 05:07:49 DEBUG : Local file system at /tmp/rclone4055992605: Waiting for transfers to finish 2022/04/09 05:07:49 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:49 INFO : potato2: Deleted 2022/04/09 05:07:49 INFO : enormous: Deleted 2022/04/09 05:07:49 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (2.91s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" sync_test.go:968: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncWithUpdateOlder (0.13s) === RUN TestSyncWithMaxDuration sync_test.go:1009: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" sync_test.go:1058: Can track renames: false 2022/04/09 05:07:50 ERROR : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Ignoring --track-renames as the source and destination do not have a common hash 2022/04/09 05:07:50 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:50 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:50 INFO : yam: Copied (new) 2022/04/09 05:07:51 INFO : potato: Copied (new) 2022/04/09 05:07:51 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:51 ERROR : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Ignoring --track-renames as the source and destination do not have a common hash 2022/04/09 05:07:51 DEBUG : potato: Sizes identical 2022/04/09 05:07:51 DEBUG : potato: Unchanged skipping 2022/04/09 05:07:51 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:51 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:52 INFO : yaml: Copied (new) 2022/04/09 05:07:52 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:52 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (2.77s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" sync_test.go:1127: Can track renames: false 2022/04/09 05:07:52 ERROR : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Ignoring --track-renames as either the source or destination do not support modtime 2022/04/09 05:07:52 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:52 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:53 INFO : yam: Copied (new) 2022/04/09 05:07:53 INFO : potato: Copied (new) 2022/04/09 05:07:53 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:53 ERROR : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Ignoring --track-renames as either the source or destination do not support modtime 2022/04/09 05:07:53 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:53 DEBUG : potato: Sizes identical 2022/04/09 05:07:53 DEBUG : potato: Unchanged skipping 2022/04/09 05:07:53 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:54 INFO : yaml: Copied (new) 2022/04/09 05:07:54 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:54 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenamesStrategyModtime (2.11s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" sync_test.go:1163: Can track renames: false 2022/04/09 05:07:55 INFO : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Making map for --track-renames 2022/04/09 05:07:55 INFO : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Finished making map for --track-renames 2022/04/09 05:07:55 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:55 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for renames to finish 2022/04/09 05:07:55 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:55 INFO : potato: Copied (new) 2022/04/09 05:07:55 INFO : sub/yam: Copied (new) 2022/04/09 05:07:55 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:56 DEBUG : potato: Sizes identical 2022/04/09 05:07:56 DEBUG : potato: Unchanged skipping 2022/04/09 05:07:56 INFO : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Making map for --track-renames 2022/04/09 05:07:56 INFO : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Finished making map for --track-renames 2022/04/09 05:07:56 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:56 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for renames to finish 2022/04/09 05:07:56 INFO : sub/yam: Moved (server-side) to: yam 2022/04/09 05:07:57 INFO : yam: Renamed from "sub/yam" 2022/04/09 05:07:57 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:57 DEBUG : Waiting for deletions to finish 2022/04/09 05:07:57 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyLeaf (3.08s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:07:58 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:07:58 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:07:58 INFO : sub dir/hello world: Copied (new) 2022/04/09 05:07:58 INFO : sub dir/hello world: Deleted 2022/04/09 05:07:59 INFO : nested/sub dir/file: Copied (new) 2022/04/09 05:07:59 INFO : nested/sub dir/file: Deleted 2022/04/09 05:07:59 INFO : sub dir: Removing directory 2022/04/09 05:07:59 INFO : nested/sub dir: Removing directory 2022/04/09 05:07:59 INFO : nested: Removing directory 2022/04/09 05:07:59 DEBUG : Local file system at /tmp/rclone4055992605: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (3.63s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:08:01 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:08:01 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:08:02 INFO : sub dir/hello world: Copied (new) 2022/04/09 05:08:02 INFO : sub dir/hello world: Deleted 2022/04/09 05:08:02 INFO : nested/sub dir/file: Copied (new) 2022/04/09 05:08:02 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (3.45s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:08:05 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:08:05 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:08:05 INFO : existing: Copied (new) 2022/04/09 05:08:05 INFO : existing: Deleted 2022/04/09 05:08:05 INFO : existing-b: Copied (new) 2022/04/09 05:08:05 INFO : existing-b: Deleted 2022/04/09 05:08:06 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:08:06 DEBUG : existing: Destination exists, skipping 2022/04/09 05:08:06 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/04/09 05:08:06 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:08:06 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (1.78s) === RUN TestServerSideMove run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:08:06 DEBUG : Creating backend with remote "TestZoho:rclone-test-wovuyuc1layikev9qetiqaj0" sync_test.go:1215: Server side move (if possible) zoho root 'rclone-test-vogovip3rixigan3kataxov1' -> zoho root 'rclone-test-wovuyuc1layikev9qetiqaj0' 2022/04/09 05:08:11 DEBUG : zoho root 'rclone-test-wovuyuc1layikev9qetiqaj0': Using server-side directory move 2022/04/09 05:08:11 INFO : zoho root 'rclone-test-wovuyuc1layikev9qetiqaj0': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2022/04/09 05:08:11 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/04/09 05:08:11 DEBUG : empty space: Sizes identical 2022/04/09 05:08:11 DEBUG : empty space: Unchanged skipping 2022/04/09 05:08:11 DEBUG : zoho root 'rclone-test-wovuyuc1layikev9qetiqaj0': Waiting for checks to finish 2022/04/09 05:08:11 INFO : empty space: Deleted 2022/04/09 05:08:11 DEBUG : zoho root 'rclone-test-wovuyuc1layikev9qetiqaj0': Waiting for transfers to finish 2022/04/09 05:08:11 INFO : potato3: Deleted 2022/04/09 05:08:12 INFO : potato2: Moved (server-side) 2022/04/09 05:08:12 INFO : potato3: Moved (server-side) 2022/04/09 05:08:12 INFO : There was nothing to transfer 2022/04/09 05:08:12 DEBUG : Creating backend with remote "TestZoho:rclone-test-rulosel3hokejay7laxizub3" 2022/04/09 05:08:12 DEBUG : zoho root 'rclone-test-rulosel3hokejay7laxizub3': Using server-side directory move 2022/04/09 05:08:13 INFO : zoho root 'rclone-test-rulosel3hokejay7laxizub3': Server side directory move succeeded fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache 2022/04/09 05:08:15 ERROR : : error listing: directory not found fstest.go:293: Sleeping for 6s just to make sure 2022/04/09 05:08:21 DEBUG : zoho root 'rclone-test-rulosel3hokejay7laxizub3': Purge remote 2022/04/09 05:08:21 DEBUG : zoho root 'rclone-test-wovuyuc1layikev9qetiqaj0': Purge remote 2022/04/09 05:08:21 purge failed: directory not found --- PASS: TestServerSideMove (15.24s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:08:22 DEBUG : Creating backend with remote "TestZoho:rclone-test-bimazec4papoyeq3jodozat9" sync_test.go:1215: Server side move (if possible) zoho root 'rclone-test-vogovip3rixigan3kataxov1' -> zoho root 'rclone-test-bimazec4papoyeq3jodozat9' 2022/04/09 05:08:27 DEBUG : empty space: Excluded 2022/04/09 05:08:27 DEBUG : empty space: Excluded 2022/04/09 05:08:27 DEBUG : zoho root 'rclone-test-bimazec4papoyeq3jodozat9': Waiting for checks to finish 2022/04/09 05:08:27 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/04/09 05:08:27 DEBUG : zoho root 'rclone-test-bimazec4papoyeq3jodozat9': Waiting for transfers to finish 2022/04/09 05:08:27 INFO : potato3: Deleted 2022/04/09 05:08:27 INFO : potato2: Moved (server-side) 2022/04/09 05:08:27 INFO : potato3: Moved (server-side) 2022/04/09 05:08:27 INFO : There was nothing to transfer 2022/04/09 05:08:27 DEBUG : Creating backend with remote "TestZoho:rclone-test-qinohud1vadomep7ledanoh6" 2022/04/09 05:08:28 DEBUG : empty space: Excluded 2022/04/09 05:08:28 DEBUG : zoho root 'rclone-test-qinohud1vadomep7ledanoh6': Waiting for checks to finish 2022/04/09 05:08:28 DEBUG : zoho root 'rclone-test-qinohud1vadomep7ledanoh6': Waiting for transfers to finish 2022/04/09 05:08:29 INFO : potato3: Moved (server-side) 2022/04/09 05:08:29 INFO : potato2: Moved (server-side) 2022/04/09 05:08:29 INFO : There was nothing to transfer 2022/04/09 05:08:30 DEBUG : zoho root 'rclone-test-qinohud1vadomep7ledanoh6': Purge remote 2022/04/09 05:08:30 DEBUG : zoho root 'rclone-test-bimazec4papoyeq3jodozat9': Purge remote --- PASS: TestServerSideMoveWithFilter (8.81s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:08:30 DEBUG : Creating backend with remote "TestZoho:rclone-test-huqozam5kufenij2heliwig1" 2022/04/09 05:08:33 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) zoho root 'rclone-test-vogovip3rixigan3kataxov1' -> zoho root 'rclone-test-huqozam5kufenij2heliwig1' 2022/04/09 05:08:35 DEBUG : zoho root 'rclone-test-huqozam5kufenij2heliwig1': Using server-side directory move 2022/04/09 05:08:35 INFO : zoho root 'rclone-test-huqozam5kufenij2heliwig1': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2022/04/09 05:08:36 DEBUG : empty space: Sizes identical 2022/04/09 05:08:36 DEBUG : empty space: Unchanged skipping 2022/04/09 05:08:36 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/04/09 05:08:36 DEBUG : zoho root 'rclone-test-huqozam5kufenij2heliwig1': Waiting for checks to finish 2022/04/09 05:08:36 INFO : empty space: Deleted 2022/04/09 05:08:36 DEBUG : zoho root 'rclone-test-huqozam5kufenij2heliwig1': Waiting for transfers to finish 2022/04/09 05:08:36 INFO : potato3: Deleted 2022/04/09 05:08:36 INFO : potato2: Moved (server-side) 2022/04/09 05:08:36 INFO : potato3: Moved (server-side) 2022/04/09 05:08:36 INFO : tomatoDir: Removing directory 2022/04/09 05:08:36 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': deleted 1 directories 2022/04/09 05:08:36 INFO : There was nothing to transfer 2022/04/09 05:08:37 DEBUG : Creating backend with remote "TestZoho:rclone-test-noduwoz3xuqufim0muginah7" 2022/04/09 05:08:37 DEBUG : tomatoDir: Making directory 2022/04/09 05:08:38 DEBUG : zoho root 'rclone-test-noduwoz3xuqufim0muginah7': Using server-side directory move 2022/04/09 05:08:38 INFO : zoho root 'rclone-test-noduwoz3xuqufim0muginah7': Server side directory move succeeded fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache 2022/04/09 05:08:40 ERROR : : error listing: directory not found fstest.go:293: Sleeping for 6s just to make sure 2022/04/09 05:08:47 ERROR : : error listing: directory not found 2022/04/09 05:08:47 DEBUG : zoho root 'rclone-test-noduwoz3xuqufim0muginah7': Purge remote 2022/04/09 05:08:47 DEBUG : zoho root 'rclone-test-huqozam5kufenij2heliwig1': Purge remote 2022/04/09 05:08:47 purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (16.83s) === RUN TestServerSideMoveOverlap run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" sync_test.go:1399: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.14s) === RUN TestSyncOverlap run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:08:47 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/rclone-sync-test" --- PASS: TestSyncOverlap (0.57s) === RUN TestSyncCompareDest run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:08:48 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/dst" 2022/04/09 05:08:48 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/CompareDest" 2022/04/09 05:08:49 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:08:49 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:08:50 INFO : one: Copied (new) 2022/04/09 05:08:50 DEBUG : Waiting for deletions to finish 2022/04/09 05:08:50 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:08:51 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/09 05:08:51 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:08:51 INFO : one: Copied (replaced existing) 2022/04/09 05:08:51 DEBUG : Waiting for deletions to finish 2022/04/09 05:08:53 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:08:53 DEBUG : one: Sizes identical 2022/04/09 05:08:53 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/09 05:08:53 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:08:53 DEBUG : Waiting for deletions to finish 2022/04/09 05:08:53 INFO : There was nothing to transfer 2022/04/09 05:08:55 DEBUG : two: Sizes identical 2022/04/09 05:08:55 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/09 05:08:55 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:08:55 DEBUG : one: Sizes identical 2022/04/09 05:08:55 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/09 05:08:55 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:08:55 DEBUG : Waiting for deletions to finish 2022/04/09 05:08:55 INFO : There was nothing to transfer 2022/04/09 05:08:56 DEBUG : two: Sizes identical 2022/04/09 05:08:56 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/09 05:08:56 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:08:56 DEBUG : one: Sizes identical 2022/04/09 05:08:56 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/09 05:08:56 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:08:56 DEBUG : Waiting for deletions to finish 2022/04/09 05:08:56 INFO : There was nothing to transfer sync_test.go:1543: No hash on uploaded file so skipping compare timestamp test 2022/04/09 05:08:57 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/04/09 05:08:57 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:08:57 DEBUG : one: Sizes identical 2022/04/09 05:08:57 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/09 05:08:57 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:08:57 INFO : two: Copied (new) 2022/04/09 05:08:57 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (11.26s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:09:01 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/dest" 2022/04/09 05:09:02 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/pre-dest1" 2022/04/09 05:09:02 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/pre-dest2" 2022/04/09 05:09:03 DEBUG : 1: Sizes identical 2022/04/09 05:09:03 DEBUG : 1: Destination found in --compare-dest, skipping 2022/04/09 05:09:03 DEBUG : 2: Sizes identical 2022/04/09 05:09:03 DEBUG : 2: Destination found in --compare-dest, skipping 2022/04/09 05:09:04 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dest': Waiting for checks to finish 2022/04/09 05:09:04 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dest': Waiting for transfers to finish 2022/04/09 05:09:04 INFO : 3: Copied (new) 2022/04/09 05:09:04 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (7.42s) === RUN TestSyncCopyDest run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:09:07 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/dst" 2022/04/09 05:09:07 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/CopyDest" 2022/04/09 05:09:08 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:08 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:09 INFO : one: Copied (new) 2022/04/09 05:09:09 DEBUG : Waiting for deletions to finish 2022/04/09 05:09:09 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:10 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/09 05:09:10 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:10 INFO : one: Copied (replaced existing) 2022/04/09 05:09:10 DEBUG : Waiting for deletions to finish 2022/04/09 05:09:12 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/BackupDir" 2022/04/09 05:09:13 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:13 DEBUG : one: Sizes identical 2022/04/09 05:09:13 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/09 05:09:14 INFO : one: Moved (server-side) 2022/04/09 05:09:14 INFO : one: Copied (server-side copy) 2022/04/09 05:09:14 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/04/09 05:09:14 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:14 DEBUG : Waiting for deletions to finish 2022/04/09 05:09:16 DEBUG : two: Sizes identical 2022/04/09 05:09:17 INFO : two: Copied (server-side copy) 2022/04/09 05:09:17 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/04/09 05:09:17 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:17 DEBUG : one: Sizes identical 2022/04/09 05:09:17 DEBUG : one: Sizes identical 2022/04/09 05:09:17 DEBUG : one: Unchanged skipping 2022/04/09 05:09:17 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:17 DEBUG : Waiting for deletions to finish 2022/04/09 05:09:17 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:17 DEBUG : one: Sizes identical 2022/04/09 05:09:17 DEBUG : one: Sizes identical 2022/04/09 05:09:17 DEBUG : one: Unchanged skipping 2022/04/09 05:09:17 DEBUG : two: Sizes identical 2022/04/09 05:09:17 DEBUG : two: Sizes identical 2022/04/09 05:09:17 DEBUG : two: Unchanged skipping 2022/04/09 05:09:17 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:17 DEBUG : Waiting for deletions to finish 2022/04/09 05:09:17 INFO : There was nothing to transfer 2022/04/09 05:09:19 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/04/09 05:09:19 DEBUG : three: Destination not found in --copy-dest 2022/04/09 05:09:19 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:19 DEBUG : two: Sizes identical 2022/04/09 05:09:19 DEBUG : two: Sizes identical 2022/04/09 05:09:19 DEBUG : two: Unchanged skipping 2022/04/09 05:09:19 DEBUG : one: Sizes identical 2022/04/09 05:09:19 DEBUG : one: Sizes identical 2022/04/09 05:09:19 DEBUG : one: Unchanged skipping 2022/04/09 05:09:19 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:20 INFO : three: Copied (new) 2022/04/09 05:09:20 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (15.46s) === RUN TestSyncBackupDir run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:09:24 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/dst" 2022/04/09 05:09:25 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/backup" 2022/04/09 05:09:26 DEBUG : two: Sizes identical 2022/04/09 05:09:26 DEBUG : two: Unchanged skipping 2022/04/09 05:09:26 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:26 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/09 05:09:26 INFO : one: Moved (server-side) 2022/04/09 05:09:26 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:27 INFO : one: Copied (new) 2022/04/09 05:09:27 DEBUG : Waiting for deletions to finish 2022/04/09 05:09:27 INFO : three.txt: Moved (server-side) 2022/04/09 05:09:27 INFO : three.txt: Moved into backup dir 2022/04/09 05:09:29 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:29 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/09 05:09:29 DEBUG : two: Sizes identical 2022/04/09 05:09:29 DEBUG : two: Unchanged skipping 2022/04/09 05:09:29 INFO : one: Deleted 2022/04/09 05:09:30 INFO : one: Moved (server-side) 2022/04/09 05:09:30 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:30 INFO : one: Copied (new) 2022/04/09 05:09:30 DEBUG : Waiting for deletions to finish 2022/04/09 05:09:30 INFO : three.txt: Deleted 2022/04/09 05:09:31 INFO : three.txt: Moved (server-side) 2022/04/09 05:09:31 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (10.59s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:09:35 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/dst" 2022/04/09 05:09:35 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/backup" 2022/04/09 05:09:36 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/09 05:09:36 DEBUG : two: Sizes identical 2022/04/09 05:09:36 DEBUG : two: Unchanged skipping 2022/04/09 05:09:36 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:37 INFO : one: Moved (server-side) to: one.bak 2022/04/09 05:09:37 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:37 INFO : one: Copied (new) 2022/04/09 05:09:37 DEBUG : Waiting for deletions to finish 2022/04/09 05:09:38 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/04/09 05:09:38 INFO : three.txt: Moved into backup dir 2022/04/09 05:09:39 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:39 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/09 05:09:39 DEBUG : two: Sizes identical 2022/04/09 05:09:39 DEBUG : two: Unchanged skipping 2022/04/09 05:09:40 INFO : one.bak: Deleted 2022/04/09 05:09:40 INFO : one: Moved (server-side) to: one.bak 2022/04/09 05:09:40 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:41 INFO : one: Copied (new) 2022/04/09 05:09:41 DEBUG : Waiting for deletions to finish 2022/04/09 05:09:41 INFO : three.txt.bak: Deleted 2022/04/09 05:09:42 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/04/09 05:09:42 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (11.18s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:09:46 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/dst" 2022/04/09 05:09:46 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/backup" 2022/04/09 05:09:47 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/09 05:09:47 DEBUG : two: Sizes identical 2022/04/09 05:09:47 DEBUG : two: Unchanged skipping 2022/04/09 05:09:47 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:48 INFO : one: Moved (server-side) to: one-2019-01-01 2022/04/09 05:09:48 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:49 INFO : one: Copied (new) 2022/04/09 05:09:49 DEBUG : Waiting for deletions to finish 2022/04/09 05:09:49 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/04/09 05:09:49 INFO : three.txt: Moved into backup dir 2022/04/09 05:09:51 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/09 05:09:51 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:51 DEBUG : two: Sizes identical 2022/04/09 05:09:51 DEBUG : two: Unchanged skipping 2022/04/09 05:09:51 INFO : one-2019-01-01: Deleted 2022/04/09 05:09:52 INFO : one: Moved (server-side) to: one-2019-01-01 2022/04/09 05:09:52 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:52 INFO : one: Copied (new) 2022/04/09 05:09:52 DEBUG : Waiting for deletions to finish 2022/04/09 05:09:52 INFO : three-2019-01-01.txt: Deleted 2022/04/09 05:09:53 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/04/09 05:09:53 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (10.93s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:09:57 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/dst" 2022/04/09 05:09:57 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/09 05:09:57 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:09:57 DEBUG : two: Sizes identical 2022/04/09 05:09:57 DEBUG : two: Unchanged skipping 2022/04/09 05:09:57 INFO : one: Moved (server-side) to: one.bak 2022/04/09 05:09:57 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:09:58 INFO : one: Copied (new) 2022/04/09 05:09:58 DEBUG : Waiting for deletions to finish 2022/04/09 05:09:58 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/04/09 05:09:58 INFO : three.txt: Moved into backup dir 2022/04/09 05:10:00 DEBUG : three.txt.bak: Excluded 2022/04/09 05:10:00 DEBUG : one.bak: Excluded 2022/04/09 05:10:00 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/09 05:10:00 DEBUG : two: Sizes identical 2022/04/09 05:10:00 DEBUG : two: Unchanged skipping 2022/04/09 05:10:00 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for checks to finish 2022/04/09 05:10:00 INFO : one.bak: Deleted 2022/04/09 05:10:00 INFO : one: Moved (server-side) to: one.bak 2022/04/09 05:10:00 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1/dst': Waiting for transfers to finish 2022/04/09 05:10:01 INFO : one: Copied (new) 2022/04/09 05:10:01 DEBUG : Waiting for deletions to finish 2022/04/09 05:10:01 INFO : three.txt.bak: Deleted 2022/04/09 05:10:01 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/04/09 05:10:01 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (8.34s) === RUN TestSyncSuffix run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:10:05 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/dst" 2022/04/09 05:10:05 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/09 05:10:06 INFO : one: Moved (server-side) to: one.bak 2022/04/09 05:10:06 INFO : one: Copied (new) 2022/04/09 05:10:07 DEBUG : two: Sizes identical 2022/04/09 05:10:07 DEBUG : two: Unchanged skipping 2022/04/09 05:10:07 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/04/09 05:10:07 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/04/09 05:10:08 INFO : three.txt: Copied (new) 2022/04/09 05:10:09 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/09 05:10:09 INFO : one.bak: Deleted 2022/04/09 05:10:09 INFO : one: Moved (server-side) to: one.bak 2022/04/09 05:10:10 INFO : one: Copied (new) 2022/04/09 05:10:10 DEBUG : two: Sizes identical 2022/04/09 05:10:10 DEBUG : two: Unchanged skipping 2022/04/09 05:10:10 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/04/09 05:10:10 INFO : three.txt.bak: Deleted 2022/04/09 05:10:11 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/04/09 05:10:11 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (10.31s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:10:15 DEBUG : Creating backend with remote "TestZoho:rclone-test-vogovip3rixigan3kataxov1/dst" 2022/04/09 05:10:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/09 05:10:16 INFO : one: Moved (server-side) to: one-2019-01-01 2022/04/09 05:10:17 INFO : one: Copied (new) 2022/04/09 05:10:17 DEBUG : two: Sizes identical 2022/04/09 05:10:17 DEBUG : two: Unchanged skipping 2022/04/09 05:10:17 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/04/09 05:10:17 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/04/09 05:10:18 INFO : three.txt: Copied (new) 2022/04/09 05:10:19 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/09 05:10:19 INFO : one-2019-01-01: Deleted 2022/04/09 05:10:19 INFO : one: Moved (server-side) to: one-2019-01-01 2022/04/09 05:10:20 INFO : one: Copied (new) 2022/04/09 05:10:20 DEBUG : two: Sizes identical 2022/04/09 05:10:20 DEBUG : two: Unchanged skipping 2022/04/09 05:10:20 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/04/09 05:10:21 INFO : three-2019-01-01.txt: Deleted 2022/04/09 05:10:21 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/04/09 05:10:21 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (9.94s) === RUN TestSyncUTFNorm run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:10:24 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:10:24 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/04/09 05:10:24 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:10:25 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/04/09 05:10:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (2.08s) === RUN TestSyncImmutable run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:10:26 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:10:26 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:10:26 INFO : existing: Copied (new) 2022/04/09 05:10:26 DEBUG : Waiting for deletions to finish 2022/04/09 05:10:26 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/04/09 05:10:26 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:10:26 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/04/09 05:10:26 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:10:26 ERROR : zoho root 'rclone-test-vogovip3rixigan3kataxov1': not deleting files as there were IO errors 2022/04/09 05:10:26 ERROR : zoho root 'rclone-test-vogovip3rixigan3kataxov1': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (1.64s) === RUN TestSyncIgnoreCase run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:10:28 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:10:28 DEBUG : existing: Sizes identical 2022/04/09 05:10:28 DEBUG : existing: Unchanged skipping 2022/04/09 05:10:28 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:10:28 DEBUG : Waiting for deletions to finish 2022/04/09 05:10:28 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.27s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Soft run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (0.49s) --- SKIP: TestMaxTransfer/Hard (0.13s) --- SKIP: TestMaxTransfer/Soft (0.14s) --- SKIP: TestMaxTransfer/Cautious (0.23s) === RUN TestSyncConcurrentDelete run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" 2022/04/09 05:11:11 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:11:11 DEBUG : both11: Sizes identical 2022/04/09 05:11:11 DEBUG : both11: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both12: Sizes identical 2022/04/09 05:11:11 DEBUG : both12: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both13: Sizes identical 2022/04/09 05:11:11 DEBUG : both13: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both14: Sizes identical 2022/04/09 05:11:11 DEBUG : both14: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both15: Sizes identical 2022/04/09 05:11:11 DEBUG : both15: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both16: Sizes identical 2022/04/09 05:11:11 DEBUG : both16: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both17: Sizes identical 2022/04/09 05:11:11 DEBUG : both17: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both18: Sizes identical 2022/04/09 05:11:11 DEBUG : both18: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both19: Sizes identical 2022/04/09 05:11:11 DEBUG : both19: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both2: Sizes identical 2022/04/09 05:11:11 DEBUG : both2: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both3: Sizes identical 2022/04/09 05:11:11 DEBUG : both3: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both4: Sizes identical 2022/04/09 05:11:11 DEBUG : both4: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both5: Sizes identical 2022/04/09 05:11:11 DEBUG : both5: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both6: Sizes identical 2022/04/09 05:11:11 DEBUG : both6: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both7: Sizes identical 2022/04/09 05:11:11 DEBUG : both7: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both0: Sizes identical 2022/04/09 05:11:11 DEBUG : both0: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both9: Sizes identical 2022/04/09 05:11:11 DEBUG : both9: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both1: Sizes identical 2022/04/09 05:11:11 DEBUG : both1: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both10: Sizes identical 2022/04/09 05:11:11 DEBUG : both10: Unchanged skipping 2022/04/09 05:11:11 DEBUG : both8: Sizes identical 2022/04/09 05:11:11 DEBUG : both8: Unchanged skipping 2022/04/09 05:11:11 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:11:11 DEBUG : Waiting for deletions to finish 2022/04/09 05:11:11 INFO : only14: Deleted 2022/04/09 05:11:11 INFO : only8: Deleted 2022/04/09 05:11:11 INFO : only2: Deleted 2022/04/09 05:11:11 INFO : only9: Deleted 2022/04/09 05:11:11 INFO : only1: Deleted 2022/04/09 05:11:11 INFO : only17: Deleted 2022/04/09 05:11:11 INFO : only3: Deleted 2022/04/09 05:11:11 INFO : only6: Deleted 2022/04/09 05:11:11 INFO : only4: Deleted 2022/04/09 05:11:11 INFO : only12: Deleted 2022/04/09 05:11:11 INFO : only11: Deleted 2022/04/09 05:11:11 INFO : only18: Deleted 2022/04/09 05:11:11 INFO : only13: Deleted 2022/04/09 05:11:11 INFO : only7: Deleted 2022/04/09 05:11:11 INFO : only10: Deleted 2022/04/09 05:11:11 INFO : only5: Deleted 2022/04/09 05:11:11 INFO : only0: Deleted 2022/04/09 05:11:11 INFO : only16: Deleted 2022/04/09 05:11:11 INFO : only15: Deleted 2022/04/09 05:11:11 INFO : only19: Deleted 2022/04/09 05:11:11 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (46.28s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "zoho root 'rclone-test-vogovip3rixigan3kataxov1'", Local "Local file system at /tmp/rclone4055992605", Modify Window "876000h0m0s" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Flushing the directory cache fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2050 sync_test.go:2066 Error: Should be true Test: TestSyncConcurrentTruncate Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (6), only1 (6), only10 (6), only11 (6), only12 (6), only13 (6), only14 (6), only15 (6), only16 (6), only17 (6), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (6), only8 (6), only9 (6) got both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (6), only1 (6), only10 (6), only11 (6), only12 (6), only13 (6), only14 (6), only15 (6), only16 (6), only17 (0), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (6), only8 (6), only9 (6) fstest.go:151: Error Trace: fstest.go:151 fstest.go:196 fstest.go:309 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2050 sync_test.go:2066 Error: Not equal: expected: 6 actual : 0 Test: TestSyncConcurrentTruncate Messages: only17: size incorrect file=6 vs obj=0 2022/04/09 05:12:08 DEBUG : both0: Sizes identical 2022/04/09 05:12:08 DEBUG : both0: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both1: Sizes identical 2022/04/09 05:12:08 DEBUG : both1: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both10: Sizes identical 2022/04/09 05:12:08 DEBUG : both10: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both11: Sizes identical 2022/04/09 05:12:08 DEBUG : both11: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both12: Sizes identical 2022/04/09 05:12:08 DEBUG : both12: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both13: Sizes identical 2022/04/09 05:12:08 DEBUG : both13: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both14: Sizes identical 2022/04/09 05:12:08 DEBUG : both14: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both15: Sizes identical 2022/04/09 05:12:08 DEBUG : both15: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both16: Sizes identical 2022/04/09 05:12:08 DEBUG : both16: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both17: Sizes identical 2022/04/09 05:12:08 DEBUG : both17: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both18: Sizes identical 2022/04/09 05:12:08 DEBUG : both18: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both19: Sizes identical 2022/04/09 05:12:08 DEBUG : both19: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both2: Sizes identical 2022/04/09 05:12:08 DEBUG : both2: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both3: Sizes identical 2022/04/09 05:12:08 DEBUG : both3: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both4: Sizes identical 2022/04/09 05:12:08 DEBUG : both4: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both5: Sizes identical 2022/04/09 05:12:08 DEBUG : both5: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both6: Sizes identical 2022/04/09 05:12:08 DEBUG : both6: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both7: Sizes identical 2022/04/09 05:12:08 DEBUG : both7: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both8: Sizes identical 2022/04/09 05:12:08 DEBUG : both8: Unchanged skipping 2022/04/09 05:12:08 DEBUG : both9: Sizes identical 2022/04/09 05:12:08 DEBUG : both9: Unchanged skipping 2022/04/09 05:12:08 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only17: Sizes identical 2022/04/09 05:12:08 DEBUG : only17: Unchanged skipping 2022/04/09 05:12:08 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/04/09 05:12:08 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for checks to finish 2022/04/09 05:12:08 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Waiting for transfers to finish 2022/04/09 05:12:08 INFO : only0: Copied (replaced existing) 2022/04/09 05:12:08 INFO : only11: Copied (replaced existing) 2022/04/09 05:12:08 INFO : only1: Copied (replaced existing) 2022/04/09 05:12:08 INFO : only10: Copied (replaced existing) 2022/04/09 05:12:09 INFO : only12: Copied (replaced existing) 2022/04/09 05:12:09 INFO : only14: Copied (replaced existing) 2022/04/09 05:12:09 INFO : only13: Copied (replaced existing) 2022/04/09 05:12:09 INFO : only15: Copied (replaced existing) 2022/04/09 05:12:09 INFO : only16: Copied (replaced existing) 2022/04/09 05:12:09 INFO : only18: Copied (replaced existing) 2022/04/09 05:12:10 INFO : only19: Copied (replaced existing) 2022/04/09 05:12:10 INFO : only2: Copied (replaced existing) 2022/04/09 05:12:10 INFO : only3: Copied (replaced existing) 2022/04/09 05:12:10 INFO : only4: Copied (replaced existing) 2022/04/09 05:12:10 INFO : only5: Copied (replaced existing) 2022/04/09 05:12:10 INFO : only7: Copied (replaced existing) 2022/04/09 05:12:10 INFO : only6: Copied (replaced existing) 2022/04/09 05:12:11 INFO : only8: Copied (replaced existing) 2022/04/09 05:12:11 INFO : only9: Copied (replaced existing) 2022/04/09 05:12:11 DEBUG : Waiting for deletions to finish --- FAIL: TestSyncConcurrentTruncate (63.23s) FAIL 2022/04/09 05:12:18 DEBUG : zoho root 'rclone-test-vogovip3rixigan3kataxov1': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestZoho: -verbose" - Finished ERROR in 6m0.658764404s (try 1/5): exit status 1: Failed [TestSyncIgnoreTimes TestSyncConcurrentTruncate]