"./sync.test -test.v -test.timeout 1h0m0s -remote TestZoho: -verbose -test.run '^(TestMaxTransfer|TestMoveWithIgnoreExisting|TestMoveWithoutDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverlap|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCompareDest|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncCopyDest|TestSyncIgnoreCase|TestSyncImmutable|TestSyncMultipleCompareDest|TestSyncOverlap|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm)$/^(Cautious|Hard|Soft)$'" - Starting (try 2/5) 2022/04/20 06:39:05 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6" 2022/04/20 06:39:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/04/20 06:39:05 DEBUG : Creating backend with remote "/tmp/rclone4179679159" === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:39:06 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for checks to finish 2022/04/20 06:39:06 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for transfers to finish 2022/04/20 06:39:07 INFO : sub dir/hello world: Copied (new) 2022/04/20 06:39:07 INFO : sub dir/hello world: Deleted 2022/04/20 06:39:07 INFO : nested/sub dir/file: Copied (new) 2022/04/20 06:39:07 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (4.10s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:39:09 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for checks to finish 2022/04/20 06:39:09 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for transfers to finish 2022/04/20 06:39:10 INFO : existing: Copied (new) 2022/04/20 06:39:10 INFO : existing: Deleted 2022/04/20 06:39:10 INFO : existing-b: Copied (new) 2022/04/20 06:39:10 INFO : existing-b: Deleted 2022/04/20 06:39:10 DEBUG : existing: Destination exists, skipping 2022/04/20 06:39:10 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/04/20 06:39:10 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for checks to finish 2022/04/20 06:39:10 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for transfers to finish 2022/04/20 06:39:10 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (1.73s) === RUN TestServerSideMove run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:39:11 DEBUG : Creating backend with remote "TestZoho:rclone-test-fopucoy1xetabub9runukuy3" sync_test.go:1215: Server side move (if possible) zoho root 'rclone-test-lihalih3dajuqaj4buyenul6' -> zoho root 'rclone-test-fopucoy1xetabub9runukuy3' 2022/04/20 06:39:15 DEBUG : zoho root 'rclone-test-fopucoy1xetabub9runukuy3': Using server-side directory move 2022/04/20 06:39:15 INFO : zoho root 'rclone-test-fopucoy1xetabub9runukuy3': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2022/04/20 06:39:16 DEBUG : empty space: Sizes identical 2022/04/20 06:39:16 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/04/20 06:39:16 DEBUG : zoho root 'rclone-test-fopucoy1xetabub9runukuy3': Waiting for checks to finish 2022/04/20 06:39:16 DEBUG : empty space: Unchanged skipping 2022/04/20 06:39:16 INFO : empty space: Deleted 2022/04/20 06:39:16 DEBUG : zoho root 'rclone-test-fopucoy1xetabub9runukuy3': Waiting for transfers to finish 2022/04/20 06:39:16 INFO : potato3: Deleted 2022/04/20 06:39:16 INFO : potato2: Moved (server-side) 2022/04/20 06:39:16 INFO : potato3: Moved (server-side) 2022/04/20 06:39:16 INFO : There was nothing to transfer 2022/04/20 06:39:16 DEBUG : Creating backend with remote "TestZoho:rclone-test-qesobey5taratan4yugilir0" 2022/04/20 06:39:17 DEBUG : zoho root 'rclone-test-qesobey5taratan4yugilir0': Using server-side directory move 2022/04/20 06:39:18 INFO : zoho root 'rclone-test-qesobey5taratan4yugilir0': 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/20 06:39:20 ERROR : : error listing: directory not found fstest.go:293: Sleeping for 6s just to make sure 2022/04/20 06:39:26 DEBUG : zoho root 'rclone-test-qesobey5taratan4yugilir0': Purge remote 2022/04/20 06:39:26 DEBUG : zoho root 'rclone-test-fopucoy1xetabub9runukuy3': Purge remote 2022/04/20 06:39:26 purge failed: directory not found --- PASS: TestServerSideMove (15.27s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:39:26 DEBUG : Creating backend with remote "TestZoho:rclone-test-parinaz7kodezof8tafiyok2" sync_test.go:1215: Server side move (if possible) zoho root 'rclone-test-lihalih3dajuqaj4buyenul6' -> zoho root 'rclone-test-parinaz7kodezof8tafiyok2' 2022/04/20 06:39:31 DEBUG : empty space: Excluded 2022/04/20 06:39:31 DEBUG : empty space: Excluded 2022/04/20 06:39:31 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/04/20 06:39:31 DEBUG : zoho root 'rclone-test-parinaz7kodezof8tafiyok2': Waiting for checks to finish 2022/04/20 06:39:31 DEBUG : zoho root 'rclone-test-parinaz7kodezof8tafiyok2': Waiting for transfers to finish 2022/04/20 06:39:31 INFO : potato3: Deleted 2022/04/20 06:39:31 INFO : potato2: Moved (server-side) 2022/04/20 06:39:31 INFO : potato3: Moved (server-side) 2022/04/20 06:39:31 INFO : There was nothing to transfer 2022/04/20 06:39:32 DEBUG : Creating backend with remote "TestZoho:rclone-test-wusatam8nulivab9nacocip6" 2022/04/20 06:39:33 DEBUG : empty space: Excluded 2022/04/20 06:39:33 DEBUG : zoho root 'rclone-test-wusatam8nulivab9nacocip6': Waiting for checks to finish 2022/04/20 06:39:33 DEBUG : zoho root 'rclone-test-wusatam8nulivab9nacocip6': Waiting for transfers to finish 2022/04/20 06:39:34 INFO : potato2: Moved (server-side) 2022/04/20 06:39:34 INFO : potato3: Moved (server-side) 2022/04/20 06:39:34 INFO : There was nothing to transfer 2022/04/20 06:39:34 DEBUG : zoho root 'rclone-test-wusatam8nulivab9nacocip6': Purge remote 2022/04/20 06:39:34 DEBUG : zoho root 'rclone-test-parinaz7kodezof8tafiyok2': Purge remote --- PASS: TestServerSideMoveWithFilter (8.59s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:39:35 DEBUG : Creating backend with remote "TestZoho:rclone-test-kosizih6rizuxoz6cikaboc7" 2022/04/20 06:39:37 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) zoho root 'rclone-test-lihalih3dajuqaj4buyenul6' -> zoho root 'rclone-test-kosizih6rizuxoz6cikaboc7' 2022/04/20 06:39:39 DEBUG : zoho root 'rclone-test-kosizih6rizuxoz6cikaboc7': Using server-side directory move 2022/04/20 06:39:39 INFO : zoho root 'rclone-test-kosizih6rizuxoz6cikaboc7': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2022/04/20 06:39:40 DEBUG : empty space: Sizes identical 2022/04/20 06:39:40 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/04/20 06:39:40 DEBUG : empty space: Unchanged skipping 2022/04/20 06:39:40 DEBUG : zoho root 'rclone-test-kosizih6rizuxoz6cikaboc7': Waiting for checks to finish 2022/04/20 06:39:40 INFO : empty space: Deleted 2022/04/20 06:39:40 DEBUG : zoho root 'rclone-test-kosizih6rizuxoz6cikaboc7': Waiting for transfers to finish 2022/04/20 06:39:40 INFO : potato3: Deleted 2022/04/20 06:39:40 INFO : potato2: Moved (server-side) 2022/04/20 06:39:40 INFO : potato3: Moved (server-side) 2022/04/20 06:39:40 INFO : tomatoDir: Removing directory 2022/04/20 06:39:40 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': deleted 1 directories 2022/04/20 06:39:40 INFO : There was nothing to transfer 2022/04/20 06:39:41 DEBUG : Creating backend with remote "TestZoho:rclone-test-navubaj0wuxolic5banocuc1" 2022/04/20 06:39:41 DEBUG : tomatoDir: Making directory 2022/04/20 06:39:42 DEBUG : zoho root 'rclone-test-navubaj0wuxolic5banocuc1': Using server-side directory move 2022/04/20 06:39:42 INFO : zoho root 'rclone-test-navubaj0wuxolic5banocuc1': 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/20 06:39:44 ERROR : : error listing: directory not found fstest.go:293: Sleeping for 6s just to make sure 2022/04/20 06:39:51 ERROR : : error listing: directory not found 2022/04/20 06:39:51 DEBUG : zoho root 'rclone-test-navubaj0wuxolic5banocuc1': Purge remote 2022/04/20 06:39:51 DEBUG : zoho root 'rclone-test-kosizih6rizuxoz6cikaboc7': Purge remote 2022/04/20 06:39:51 purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (16.51s) === RUN TestServerSideMoveOverlap run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", 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-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:39:52 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/rclone-sync-test" --- PASS: TestSyncOverlap (0.60s) === RUN TestSyncCompareDest run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:39:52 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/dst" 2022/04/20 06:39:53 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/CompareDest" 2022/04/20 06:39:53 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:39:53 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:39:54 INFO : one: Copied (new) 2022/04/20 06:39:54 DEBUG : Waiting for deletions to finish 2022/04/20 06:39:55 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:39:55 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/20 06:39:55 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:39:55 INFO : one: Copied (replaced existing) 2022/04/20 06:39:55 DEBUG : Waiting for deletions to finish 2022/04/20 06:39:57 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:39:57 DEBUG : one: Sizes identical 2022/04/20 06:39:57 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/20 06:39:57 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:39:57 DEBUG : Waiting for deletions to finish 2022/04/20 06:39:57 INFO : There was nothing to transfer 2022/04/20 06:39:59 DEBUG : two: Sizes identical 2022/04/20 06:39:59 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/20 06:39:59 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:39:59 DEBUG : one: Sizes identical 2022/04/20 06:39:59 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/20 06:39:59 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:39:59 DEBUG : Waiting for deletions to finish 2022/04/20 06:39:59 INFO : There was nothing to transfer 2022/04/20 06:39:59 DEBUG : two: Sizes identical 2022/04/20 06:39:59 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/20 06:39:59 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:39:59 DEBUG : one: Sizes identical 2022/04/20 06:39:59 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/20 06:39:59 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:39:59 DEBUG : Waiting for deletions to finish 2022/04/20 06:39:59 INFO : There was nothing to transfer sync_test.go:1543: No hash on uploaded file so skipping compare timestamp test 2022/04/20 06:40:00 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/04/20 06:40:00 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:01 DEBUG : one: Sizes identical 2022/04/20 06:40:01 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/20 06:40:01 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:01 INFO : two: Copied (new) 2022/04/20 06:40:01 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (10.70s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:40:05 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/dest" 2022/04/20 06:40:06 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/pre-dest1" 2022/04/20 06:40:06 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/pre-dest2" 2022/04/20 06:40:07 DEBUG : 1: Sizes identical 2022/04/20 06:40:07 DEBUG : 1: Destination found in --compare-dest, skipping 2022/04/20 06:40:07 DEBUG : 2: Sizes identical 2022/04/20 06:40:07 DEBUG : 2: Destination found in --compare-dest, skipping 2022/04/20 06:40:07 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dest': Waiting for checks to finish 2022/04/20 06:40:07 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dest': Waiting for transfers to finish 2022/04/20 06:40:08 INFO : 3: Copied (new) 2022/04/20 06:40:08 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (7.14s) === RUN TestSyncCopyDest run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:40:10 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/dst" 2022/04/20 06:40:10 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/CopyDest" 2022/04/20 06:40:11 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:11 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:12 INFO : one: Copied (new) 2022/04/20 06:40:12 DEBUG : Waiting for deletions to finish 2022/04/20 06:40:12 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:13 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/20 06:40:13 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:13 INFO : one: Copied (replaced existing) 2022/04/20 06:40:13 DEBUG : Waiting for deletions to finish 2022/04/20 06:40:15 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/BackupDir" 2022/04/20 06:40:16 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:16 DEBUG : one: Sizes identical 2022/04/20 06:40:16 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/20 06:40:16 INFO : one: Moved (server-side) 2022/04/20 06:40:17 INFO : one: Copied (server-side copy) 2022/04/20 06:40:17 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/04/20 06:40:17 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:17 DEBUG : Waiting for deletions to finish 2022/04/20 06:40:18 DEBUG : two: Sizes identical 2022/04/20 06:40:19 INFO : two: Copied (server-side copy) 2022/04/20 06:40:19 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/04/20 06:40:19 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:19 DEBUG : one: Sizes identical 2022/04/20 06:40:19 DEBUG : one: Sizes identical 2022/04/20 06:40:19 DEBUG : one: Unchanged skipping 2022/04/20 06:40:19 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:19 DEBUG : Waiting for deletions to finish 2022/04/20 06:40:20 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:20 DEBUG : two: Sizes identical 2022/04/20 06:40:20 DEBUG : two: Sizes identical 2022/04/20 06:40:20 DEBUG : two: Unchanged skipping 2022/04/20 06:40:20 DEBUG : one: Sizes identical 2022/04/20 06:40:20 DEBUG : one: Sizes identical 2022/04/20 06:40:20 DEBUG : one: Unchanged skipping 2022/04/20 06:40:20 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:20 DEBUG : Waiting for deletions to finish 2022/04/20 06:40:20 INFO : There was nothing to transfer 2022/04/20 06:40:21 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/04/20 06:40:21 DEBUG : three: Destination not found in --copy-dest 2022/04/20 06:40:21 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:21 DEBUG : one: Sizes identical 2022/04/20 06:40:21 DEBUG : one: Sizes identical 2022/04/20 06:40:21 DEBUG : one: Unchanged skipping 2022/04/20 06:40:21 DEBUG : two: Sizes identical 2022/04/20 06:40:21 DEBUG : two: Sizes identical 2022/04/20 06:40:21 DEBUG : two: Unchanged skipping 2022/04/20 06:40:21 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:22 INFO : three: Copied (new) 2022/04/20 06:40:22 DEBUG : Waiting for deletions to finish run.go:126: removing dir "CopyDest" failed - try 1/3: directory not empty --- PASS: TestSyncCopyDest (15.32s) === RUN TestSyncBackupDir run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:40:27 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/dst" 2022/04/20 06:40:27 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/backup" 2022/04/20 06:40:28 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/20 06:40:28 DEBUG : two: Sizes identical 2022/04/20 06:40:28 DEBUG : two: Unchanged skipping 2022/04/20 06:40:28 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:29 INFO : one: Moved (server-side) 2022/04/20 06:40:29 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:30 INFO : one: Copied (new) 2022/04/20 06:40:30 DEBUG : Waiting for deletions to finish 2022/04/20 06:40:30 INFO : three.txt: Moved (server-side) 2022/04/20 06:40:30 INFO : three.txt: Moved into backup dir 2022/04/20 06:40:32 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:32 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/20 06:40:32 DEBUG : two: Sizes identical 2022/04/20 06:40:32 DEBUG : two: Unchanged skipping 2022/04/20 06:40:32 INFO : one: Deleted 2022/04/20 06:40:32 INFO : one: Moved (server-side) 2022/04/20 06:40:32 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:33 INFO : one: Copied (new) 2022/04/20 06:40:33 DEBUG : Waiting for deletions to finish 2022/04/20 06:40:34 INFO : three.txt: Deleted 2022/04/20 06:40:34 INFO : three.txt: Moved (server-side) 2022/04/20 06:40:34 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (10.71s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:40:38 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/dst" 2022/04/20 06:40:38 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/backup" 2022/04/20 06:40:39 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:39 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/20 06:40:39 DEBUG : two: Sizes identical 2022/04/20 06:40:39 DEBUG : two: Unchanged skipping 2022/04/20 06:40:40 INFO : one: Moved (server-side) to: one.bak 2022/04/20 06:40:40 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:40 INFO : one: Copied (new) 2022/04/20 06:40:40 DEBUG : Waiting for deletions to finish 2022/04/20 06:40:41 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/04/20 06:40:41 INFO : three.txt: Moved into backup dir 2022/04/20 06:40:43 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:43 DEBUG : two: Sizes identical 2022/04/20 06:40:43 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/20 06:40:43 DEBUG : two: Unchanged skipping 2022/04/20 06:40:43 INFO : one.bak: Deleted 2022/04/20 06:40:43 INFO : one: Moved (server-side) to: one.bak 2022/04/20 06:40:43 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:44 INFO : one: Copied (new) 2022/04/20 06:40:44 DEBUG : Waiting for deletions to finish 2022/04/20 06:40:44 INFO : three.txt.bak: Deleted 2022/04/20 06:40:45 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/04/20 06:40:45 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (10.31s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:40:48 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/dst" 2022/04/20 06:40:49 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/backup" 2022/04/20 06:40:49 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:49 DEBUG : two: Sizes identical 2022/04/20 06:40:49 DEBUG : two: Unchanged skipping 2022/04/20 06:40:49 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/20 06:40:50 INFO : one: Moved (server-side) to: one-2019-01-01 2022/04/20 06:40:50 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:51 INFO : one: Copied (new) 2022/04/20 06:40:51 DEBUG : Waiting for deletions to finish 2022/04/20 06:40:53 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/04/20 06:40:53 INFO : three.txt: Moved into backup dir 2022/04/20 06:40:55 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/20 06:40:55 DEBUG : two: Sizes identical 2022/04/20 06:40:55 DEBUG : two: Unchanged skipping 2022/04/20 06:40:55 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:40:55 INFO : one-2019-01-01: Deleted 2022/04/20 06:40:55 INFO : one: Moved (server-side) to: one-2019-01-01 2022/04/20 06:40:55 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:40:56 INFO : one: Copied (new) 2022/04/20 06:40:56 DEBUG : Waiting for deletions to finish 2022/04/20 06:40:56 INFO : three-2019-01-01.txt: Deleted 2022/04/20 06:40:57 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/04/20 06:40:57 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (11.96s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:41:00 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/dst" 2022/04/20 06:41:01 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/20 06:41:01 DEBUG : two: Sizes identical 2022/04/20 06:41:01 DEBUG : two: Unchanged skipping 2022/04/20 06:41:01 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:41:01 INFO : one: Moved (server-side) to: one.bak 2022/04/20 06:41:01 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:41:02 INFO : one: Copied (new) 2022/04/20 06:41:02 DEBUG : Waiting for deletions to finish 2022/04/20 06:41:02 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/04/20 06:41:02 INFO : three.txt: Moved into backup dir 2022/04/20 06:41:04 DEBUG : three.txt.bak: Excluded 2022/04/20 06:41:04 DEBUG : one.bak: Excluded 2022/04/20 06:41:04 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/20 06:41:04 DEBUG : two: Sizes identical 2022/04/20 06:41:04 DEBUG : two: Unchanged skipping 2022/04/20 06:41:04 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for checks to finish 2022/04/20 06:41:04 INFO : one.bak: Deleted 2022/04/20 06:41:04 INFO : one: Moved (server-side) to: one.bak 2022/04/20 06:41:04 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6/dst': Waiting for transfers to finish 2022/04/20 06:41:05 INFO : one: Copied (new) 2022/04/20 06:41:05 DEBUG : Waiting for deletions to finish 2022/04/20 06:41:05 INFO : three.txt.bak: Deleted 2022/04/20 06:41:05 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/04/20 06:41:05 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (8.51s) === RUN TestSyncSuffix run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:41:09 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/dst" 2022/04/20 06:41:09 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/20 06:41:09 INFO : one: Moved (server-side) to: one.bak 2022/04/20 06:41:10 INFO : one: Copied (new) 2022/04/20 06:41:10 DEBUG : two: Sizes identical 2022/04/20 06:41:10 DEBUG : two: Unchanged skipping 2022/04/20 06:41:10 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/04/20 06:41:11 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/04/20 06:41:11 INFO : three.txt: Copied (new) 2022/04/20 06:41:12 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/20 06:41:12 INFO : one.bak: Deleted 2022/04/20 06:41:12 INFO : one: Moved (server-side) to: one.bak 2022/04/20 06:41:13 INFO : one: Copied (new) 2022/04/20 06:41:13 DEBUG : two: Sizes identical 2022/04/20 06:41:13 DEBUG : two: Unchanged skipping 2022/04/20 06:41:13 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/04/20 06:41:13 INFO : three.txt.bak: Deleted 2022/04/20 06:41:14 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/04/20 06:41:14 ERROR : three.txt: corrupted on transfer: sizes differ 19 vs 0 2022/04/20 06:41:14 INFO : three.txt: Removing failed copy sync_test.go:1859: Error Trace: sync_test.go:1859 sync_test.go:1875 Error: Received unexpected error: corrupted on transfer: sizes differ 19 vs 0 Test: TestSyncSuffix --- FAIL: TestSyncSuffix (8.71s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:41:17 DEBUG : Creating backend with remote "TestZoho:rclone-test-lihalih3dajuqaj4buyenul6/dst" 2022/04/20 06:41:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/20 06:41:18 INFO : one: Moved (server-side) to: one-2019-01-01 2022/04/20 06:41:19 INFO : one: Copied (new) 2022/04/20 06:41:19 DEBUG : two: Sizes identical 2022/04/20 06:41:19 DEBUG : two: Unchanged skipping 2022/04/20 06:41:19 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/04/20 06:41:19 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/04/20 06:41:20 INFO : three.txt: Copied (new) 2022/04/20 06:41:21 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/20 06:41:21 INFO : one-2019-01-01: Deleted 2022/04/20 06:41:21 INFO : one: Moved (server-side) to: one-2019-01-01 2022/04/20 06:41:22 INFO : one: Copied (new) 2022/04/20 06:41:22 DEBUG : two: Sizes identical 2022/04/20 06:41:22 DEBUG : two: Unchanged skipping 2022/04/20 06:41:22 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/04/20 06:41:22 INFO : three-2019-01-01.txt: Deleted 2022/04/20 06:41:22 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/04/20 06:41:23 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (9.42s) === RUN TestSyncUTFNorm run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:41:26 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/04/20 06:41:26 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for checks to finish 2022/04/20 06:41:26 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for transfers to finish 2022/04/20 06:41:26 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/04/20 06:41:26 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.90s) === RUN TestSyncImmutable run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:41:27 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for checks to finish 2022/04/20 06:41:27 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for transfers to finish 2022/04/20 06:41:27 INFO : existing: Copied (new) 2022/04/20 06:41:27 DEBUG : Waiting for deletions to finish 2022/04/20 06:41:28 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for checks to finish 2022/04/20 06:41:28 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/04/20 06:41:28 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/04/20 06:41:28 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for transfers to finish 2022/04/20 06:41:28 ERROR : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': not deleting files as there were IO errors 2022/04/20 06:41:28 ERROR : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (1.58s) === RUN TestSyncIgnoreCase run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" 2022/04/20 06:41:29 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for checks to finish 2022/04/20 06:41:29 DEBUG : existing: Sizes identical 2022/04/20 06:41:29 DEBUG : existing: Unchanged skipping 2022/04/20 06:41:29 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for transfers to finish 2022/04/20 06:41:29 DEBUG : Waiting for deletions to finish 2022/04/20 06:41:29 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.31s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Soft run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", Modify Window "876000h0m0s" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (0.40s) --- SKIP: TestMaxTransfer/Hard (0.13s) --- SKIP: TestMaxTransfer/Soft (0.13s) --- SKIP: TestMaxTransfer/Cautious (0.14s) === RUN TestSyncConcurrentDelete run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", 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:2062 Error: Should be true Test: TestSyncConcurrentDelete 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 (0), 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) 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:2062 Error: Not equal: expected: 6 actual : 0 Test: TestSyncConcurrentDelete Messages: both7: size incorrect file=6 vs obj=0 2022/04/20 06:42:19 DEBUG : both1: Sizes identical 2022/04/20 06:42:19 DEBUG : both0: Sizes identical 2022/04/20 06:42:19 DEBUG : both0: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both1: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both10: Sizes identical 2022/04/20 06:42:19 DEBUG : both10: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both11: Sizes identical 2022/04/20 06:42:19 DEBUG : both11: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both12: Sizes identical 2022/04/20 06:42:19 DEBUG : both12: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both13: Sizes identical 2022/04/20 06:42:19 DEBUG : both13: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both14: Sizes identical 2022/04/20 06:42:19 DEBUG : both14: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both15: Sizes identical 2022/04/20 06:42:19 DEBUG : both15: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both16: Sizes identical 2022/04/20 06:42:19 DEBUG : both16: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both17: Sizes identical 2022/04/20 06:42:19 DEBUG : both17: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both18: Sizes identical 2022/04/20 06:42:19 DEBUG : both18: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both19: Sizes identical 2022/04/20 06:42:19 DEBUG : both19: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both3: Sizes identical 2022/04/20 06:42:19 DEBUG : both3: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both2: Sizes identical 2022/04/20 06:42:19 DEBUG : both4: Sizes identical 2022/04/20 06:42:19 DEBUG : both2: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both4: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both5: Sizes identical 2022/04/20 06:42:19 DEBUG : both6: Sizes identical 2022/04/20 06:42:19 DEBUG : both5: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both6: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both7: Sizes differ (src 6 vs dst 0) 2022/04/20 06:42:19 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for checks to finish 2022/04/20 06:42:19 DEBUG : both8: Sizes identical 2022/04/20 06:42:19 DEBUG : both8: Unchanged skipping 2022/04/20 06:42:19 DEBUG : both9: Sizes identical 2022/04/20 06:42:19 DEBUG : both9: Unchanged skipping 2022/04/20 06:42:19 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for transfers to finish 2022/04/20 06:42:20 INFO : both7: Copied (replaced existing) 2022/04/20 06:42:20 DEBUG : Waiting for deletions to finish 2022/04/20 06:42:20 INFO : only17: Deleted 2022/04/20 06:42:20 INFO : only6: Deleted 2022/04/20 06:42:20 INFO : only18: Deleted 2022/04/20 06:42:20 INFO : only2: Deleted 2022/04/20 06:42:20 INFO : only8: Deleted 2022/04/20 06:42:20 INFO : only9: Deleted 2022/04/20 06:42:20 INFO : only0: Deleted 2022/04/20 06:42:20 INFO : only19: Deleted 2022/04/20 06:42:20 INFO : only14: Deleted 2022/04/20 06:42:20 INFO : only5: Deleted 2022/04/20 06:42:20 INFO : only4: Deleted 2022/04/20 06:42:20 INFO : only16: Deleted 2022/04/20 06:42:20 INFO : only1: Deleted 2022/04/20 06:42:20 INFO : only13: Deleted 2022/04/20 06:42:20 INFO : only12: Deleted 2022/04/20 06:42:20 INFO : only3: Deleted 2022/04/20 06:42:20 INFO : only15: Deleted 2022/04/20 06:42:21 INFO : only10: Deleted 2022/04/20 06:42:21 INFO : only7: Deleted 2022/04/20 06:42:21 INFO : only11: Deleted --- FAIL: TestSyncConcurrentDelete (54.13s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "zoho root 'rclone-test-lihalih3dajuqaj4buyenul6'", Local "Local file system at /tmp/rclone4179679159", 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 (6), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (0), 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: only7: size incorrect file=6 vs obj=0 2022/04/20 06:43:13 DEBUG : both10: Sizes identical 2022/04/20 06:43:13 DEBUG : both10: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both11: Sizes identical 2022/04/20 06:43:13 DEBUG : both11: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both13: Sizes identical 2022/04/20 06:43:13 DEBUG : both13: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both14: Sizes identical 2022/04/20 06:43:13 DEBUG : both14: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both15: Sizes identical 2022/04/20 06:43:13 DEBUG : both15: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both16: Sizes identical 2022/04/20 06:43:13 DEBUG : both16: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both17: Sizes identical 2022/04/20 06:43:13 DEBUG : both17: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both18: Sizes identical 2022/04/20 06:43:13 DEBUG : both18: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both19: Sizes identical 2022/04/20 06:43:13 DEBUG : both19: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both2: Sizes identical 2022/04/20 06:43:13 DEBUG : both2: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both3: Sizes identical 2022/04/20 06:43:13 DEBUG : both3: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both4: Sizes identical 2022/04/20 06:43:13 DEBUG : both0: Sizes identical 2022/04/20 06:43:13 DEBUG : both0: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both5: Sizes identical 2022/04/20 06:43:13 DEBUG : both5: Unchanged skipping 2022/04/20 06:43:13 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for checks to finish 2022/04/20 06:43:13 DEBUG : both12: Sizes identical 2022/04/20 06:43:13 DEBUG : both12: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both7: Sizes identical 2022/04/20 06:43:13 DEBUG : both7: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both8: Sizes identical 2022/04/20 06:43:13 DEBUG : both8: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both9: Sizes identical 2022/04/20 06:43:13 DEBUG : both9: Unchanged skipping 2022/04/20 06:43:13 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : both6: Sizes identical 2022/04/20 06:43:13 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : both6: Unchanged skipping 2022/04/20 06:43:13 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : both1: Sizes identical 2022/04/20 06:43:13 DEBUG : both1: Unchanged skipping 2022/04/20 06:43:13 DEBUG : both4: Unchanged skipping 2022/04/20 06:43:13 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only7: Sizes identical 2022/04/20 06:43:13 DEBUG : only7: Unchanged skipping 2022/04/20 06:43:13 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/04/20 06:43:13 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Waiting for transfers to finish 2022/04/20 06:43:14 INFO : only1: Copied (replaced existing) 2022/04/20 06:43:14 INFO : only11: Copied (replaced existing) 2022/04/20 06:43:14 INFO : only10: Copied (replaced existing) 2022/04/20 06:43:14 INFO : only0: Copied (replaced existing) 2022/04/20 06:43:14 INFO : only14: Copied (replaced existing) 2022/04/20 06:43:14 INFO : only12: Copied (replaced existing) 2022/04/20 06:43:14 INFO : only18: Copied (replaced existing) 2022/04/20 06:43:14 INFO : only17: Copied (replaced existing) 2022/04/20 06:43:15 INFO : only2: Copied (replaced existing) 2022/04/20 06:43:15 INFO : only19: Copied (replaced existing) 2022/04/20 06:43:15 INFO : only4: Copied (replaced existing) 2022/04/20 06:43:15 INFO : only3: Copied (replaced existing) 2022/04/20 06:43:16 INFO : only8: Copied (replaced existing) 2022/04/20 06:43:16 INFO : only9: Copied (replaced existing) 2022/04/20 06:43:16 INFO : only5: Copied (replaced existing) 2022/04/20 06:43:16 INFO : only6: Copied (replaced existing) 2022/04/20 06:43:16 INFO : only16: Copied (replaced existing) 2022/04/20 06:43:17 INFO : only15: Copied (replaced existing) 2022/04/20 06:43:17 INFO : only13: Copied (replaced existing) 2022/04/20 06:43:17 DEBUG : Waiting for deletions to finish --- FAIL: TestSyncConcurrentTruncate (60.51s) FAIL 2022/04/20 06:43:25 DEBUG : zoho root 'rclone-test-lihalih3dajuqaj4buyenul6': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestZoho: -verbose -test.run '^(TestMaxTransfer|TestMoveWithIgnoreExisting|TestMoveWithoutDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverlap|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCompareDest|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncCopyDest|TestSyncIgnoreCase|TestSyncImmutable|TestSyncMultipleCompareDest|TestSyncOverlap|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm)$/^(Cautious|Hard|Soft)$'" - Finished ERROR in 4m20.49151894s (try 2/5): exit status 1: Failed [TestSyncSuffix TestSyncConcurrentDelete TestSyncConcurrentTruncate]