"./sync.test -test.v -test.timeout 30m0s -remote TestUnion: -verbose -test.run '^(TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension)$'" - Starting (try 5/5) === RUN TestServerSideMove 2020/04/15 06:51:04 DEBUG : union root 'rclone-test-kujesow5filomor2gohunif0': Using server side directory move 2020/04/15 06:51:04 INFO : union root 'rclone-test-kujesow5filomor2gohunif0': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2020/04/15 06:51:04 INFO : potato2: Moved (server side) 2020/04/15 06:51:04 DEBUG : union root 'rclone-test-kujesow5filomor2gohunif0': Waiting for checks to finish 2020/04/15 06:51:04 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/04/15 06:51:04 DEBUG : empty space: Unchanged skipping 2020/04/15 06:51:04 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/04/15 06:51:04 INFO : potato3: Deleted 2020/04/15 06:51:04 INFO : empty space: Deleted 2020/04/15 06:51:04 DEBUG : union root 'rclone-test-kujesow5filomor2gohunif0': Waiting for transfers to finish 2020/04/15 06:51:04 INFO : potato3: Moved (server side) 2020/04/15 06:51:04 INFO : There was nothing to transfer 2020/04/15 06:53:10 DEBUG : union root 'rclone-test-yuluyon8qovuqug2zifemir0': Using server side directory move 2020/04/15 06:53:10 INFO : union root 'rclone-test-yuluyon8qovuqug2zifemir0': Server side directory move succeeded 2020/04/15 06:54:13 ERROR : : error listing: directory not found 2020/04/15 06:54:13 DEBUG : union root 'rclone-test-yuluyon8qovuqug2zifemir0': Purge remote 2020/04/15 06:54:13 DEBUG : union root 'rclone-test-kujesow5filomor2gohunif0': Purge remote 2020/04/15 06:54:13 purge failed: object not found --- FAIL: TestServerSideMove (189.04s) run.go:176: Remote "union root 'rclone-test-patocux3quhomuz8velohub4'", Local "Local file system at /tmp/rclone903742308", Modify Window "1ns" sync_test.go:1174: Server side move (if possible) union root 'rclone-test-patocux3quhomuz8velohub4' -> union root 'rclone-test-kujesow5filomor2gohunif0' fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1189 sync_test.go:1276 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want got potato2 (60), potato3 (68) fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1189 sync_test.go:1276 Error: Should be true Test: TestServerSideMove Messages: Unexpected file "potato2" fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1189 sync_test.go:1276 Error: Should be true Test: TestServerSideMove Messages: Unexpected file "potato3" fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1196 sync_test.go:1276 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1) fstest.go:194: Not found "potato2" fstest.go:194: Not found "potato3" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1196 sync_test.go:1276 Error: Not equal: expected: 0 actual : 2 Test: TestServerSideMove Messages: 2 objects not found fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1217 sync_test.go:1276 Error: Should be true Test: TestServerSideMove Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1) fstest.go:194: Not found "potato2" fstest.go:194: Not found "potato3" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1217 sync_test.go:1276 Error: Not equal: expected: 0 actual : 2 Test: TestServerSideMove Messages: 2 objects not found === RUN TestServerSideMoveWithFilter 2020/04/15 06:54:13 DEBUG : empty space: Excluded 2020/04/15 06:54:13 DEBUG : empty space: Excluded 2020/04/15 06:54:13 DEBUG : union root 'rclone-test-piwupux7lanimik3nigezuf9': Waiting for checks to finish 2020/04/15 06:54:13 INFO : potato2: Moved (server side) 2020/04/15 06:54:13 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/04/15 06:54:13 DEBUG : union root 'rclone-test-piwupux7lanimik3nigezuf9': Waiting for transfers to finish 2020/04/15 06:54:13 INFO : potato3: Deleted 2020/04/15 06:54:13 INFO : potato3: Moved (server side) 2020/04/15 06:54:13 INFO : There was nothing to transfer 2020/04/15 06:56:19 DEBUG : empty space: Excluded 2020/04/15 06:56:19 DEBUG : union root 'rclone-test-jekuyuk6pikofay9zisezoy4': Waiting for checks to finish 2020/04/15 06:56:19 DEBUG : union root 'rclone-test-jekuyuk6pikofay9zisezoy4': Waiting for transfers to finish 2020/04/15 06:56:19 INFO : There was nothing to transfer 2020/04/15 06:56:19 ERROR : : error listing: directory not found 2020/04/15 06:56:20 ERROR : : error listing: directory not found 2020/04/15 06:56:22 ERROR : : error listing: directory not found 2020/04/15 06:56:26 ERROR : : error listing: directory not found 2020/04/15 06:56:34 ERROR : : error listing: directory not found 2020/04/15 06:56:50 ERROR : : error listing: directory not found 2020/04/15 06:57:22 DEBUG : union root 'rclone-test-jekuyuk6pikofay9zisezoy4': Purge remote 2020/04/15 06:57:22 purge failed: object not found 2020/04/15 06:57:22 DEBUG : union root 'rclone-test-piwupux7lanimik3nigezuf9': Purge remote --- FAIL: TestServerSideMoveWithFilter (189.04s) run.go:176: Remote "union root 'rclone-test-patocux3quhomuz8velohub4'", Local "Local file system at /tmp/rclone903742308", Modify Window "1ns" sync_test.go:1174: Server side move (if possible) union root 'rclone-test-patocux3quhomuz8velohub4' -> union root 'rclone-test-piwupux7lanimik3nigezuf9' fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1187 sync_test.go:1289 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1) got empty space (1), potato2 (60), potato3 (68) fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1187 sync_test.go:1289 Error: Should be true Test: TestServerSideMoveWithFilter Messages: Unexpected file "potato2" fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1187 sync_test.go:1289 Error: Should be true Test: TestServerSideMoveWithFilter Messages: Unexpected file "potato3" fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1196 sync_test.go:1289 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1) fstest.go:194: Not found "potato3" fstest.go:194: Not found "potato2" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1196 sync_test.go:1289 Error: Not equal: expected: 0 actual : 2 Test: TestServerSideMoveWithFilter Messages: 2 objects not found fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1214 sync_test.go:1289 Error: Should be true Test: TestServerSideMoveWithFilter Messages: listing wrong, want potato2 (60), potato3 (68) got fstest.go:194: Not found "potato2" fstest.go:194: Not found "potato3" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1214 sync_test.go:1289 Error: Not equal: expected: 0 actual : 2 Test: TestServerSideMoveWithFilter Messages: 2 objects not found === RUN TestServerSideMoveDeleteEmptySourceDirs 2020/04/15 06:57:22 DEBUG : tomatoDir: Making directory 2020/04/15 06:57:22 DEBUG : union root 'rclone-test-cicumin3johuged8hatazox4': Using server side directory move 2020/04/15 06:57:22 INFO : union root 'rclone-test-cicumin3johuged8hatazox4': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2020/04/15 06:57:22 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/04/15 06:57:22 DEBUG : empty space: Unchanged skipping 2020/04/15 06:57:22 INFO : potato2: Moved (server side) 2020/04/15 06:57:22 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2020/04/15 06:57:22 INFO : empty space: Deleted 2020/04/15 06:57:22 DEBUG : union root 'rclone-test-cicumin3johuged8hatazox4': Waiting for checks to finish 2020/04/15 06:57:22 INFO : potato3: Deleted 2020/04/15 06:57:22 DEBUG : union root 'rclone-test-cicumin3johuged8hatazox4': Waiting for transfers to finish 2020/04/15 06:57:22 INFO : potato3: Moved (server side) 2020/04/15 06:57:22 DEBUG : tomatoDir: Removing directory 2020/04/15 06:57:22 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4': deleted 1 directories 2020/04/15 06:57:22 INFO : There was nothing to transfer 2020/04/15 07:00:31 DEBUG : tomatoDir: Making directory 2020/04/15 07:00:31 DEBUG : union root 'rclone-test-nidilus4xoperek0wuruhiv2': Using server side directory move 2020/04/15 07:00:31 INFO : union root 'rclone-test-nidilus4xoperek0wuruhiv2': Server side directory move succeeded 2020/04/15 07:01:34 ERROR : : error listing: directory not found 2020/04/15 07:01:34 ERROR : : error listing: directory not found 2020/04/15 07:01:34 DEBUG : union root 'rclone-test-nidilus4xoperek0wuruhiv2': Purge remote 2020/04/15 07:01:34 DEBUG : union root 'rclone-test-cicumin3johuged8hatazox4': Purge remote 2020/04/15 07:01:34 purge failed: object not found --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (252.04s) run.go:176: Remote "union root 'rclone-test-patocux3quhomuz8velohub4'", Local "Local file system at /tmp/rclone903742308", Modify Window "1ns" sync_test.go:1174: Server side move (if possible) union root 'rclone-test-patocux3quhomuz8velohub4' -> union root 'rclone-test-cicumin3johuged8hatazox4' fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1189 sync_test.go:1296 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want got potato2 (60), potato3 (68) fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1189 sync_test.go:1296 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: Unexpected file "potato2" fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1189 sync_test.go:1296 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: Unexpected file "potato3" fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 sync_test.go:1193 sync_test.go:1296 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want got potato2 (60), potato3 (68) fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 sync_test.go:1193 sync_test.go:1296 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: Unexpected file "potato2" fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 sync_test.go:1193 sync_test.go:1296 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: Unexpected file "potato3" fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1196 sync_test.go:1296 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1) fstest.go:194: Not found "potato2" fstest.go:194: Not found "potato3" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1196 sync_test.go:1296 Error: Not equal: expected: 0 actual : 2 Test: TestServerSideMoveDeleteEmptySourceDirs Messages: 2 objects not found fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1217 sync_test.go:1296 Error: Should be true Test: TestServerSideMoveDeleteEmptySourceDirs Messages: listing wrong, want empty space (1), potato2 (60), potato3 (68) got empty space (1) fstest.go:194: Not found "potato2" fstest.go:194: Not found "potato3" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1217 sync_test.go:1296 Error: Not equal: expected: 0 actual : 2 Test: TestServerSideMoveDeleteEmptySourceDirs Messages: 2 objects not found === RUN TestSyncBackupDir 2020/04/15 07:01:34 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4/dst': Waiting for checks to finish 2020/04/15 07:01:34 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/04/15 07:01:34 INFO : one: Moved (server side) 2020/04/15 07:01:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/04/15 07:01:34 DEBUG : two: Unchanged skipping 2020/04/15 07:01:34 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4/dst': Waiting for transfers to finish 2020/04/15 07:01:34 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/04/15 07:01:34 INFO : one: Copied (new) 2020/04/15 07:01:34 DEBUG : Waiting for deletions to finish 2020/04/15 07:01:34 INFO : three.txt: Moved (server side) 2020/04/15 07:01:34 INFO : three.txt: Moved into backup dir 2020/04/15 07:03:40 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/04/15 07:03:40 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4/dst': Waiting for checks to finish 2020/04/15 07:03:40 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/04/15 07:03:40 DEBUG : two: Unchanged skipping 2020/04/15 07:03:40 INFO : one: Moved (server side) 2020/04/15 07:03:40 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4/dst': Waiting for transfers to finish 2020/04/15 07:03:40 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/04/15 07:03:40 INFO : one: Copied (new) 2020/04/15 07:03:40 DEBUG : Waiting for deletions to finish 2020/04/15 07:03:40 INFO : three.txt: Moved (server side) 2020/04/15 07:03:40 INFO : three.txt: Moved into backup dir --- FAIL: TestSyncBackupDir (189.04s) run.go:176: Remote "union root 'rclone-test-patocux3quhomuz8velohub4'", Local "Local file system at /tmp/rclone903742308", Modify Window "1ns" fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1587 sync_test.go:1614 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (3), backup/three.txt (5), dst/one (4), dst/two (3) got dst/one (4), dst/three.txt (5), dst/two (3) fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1587 sync_test.go:1614 Error: Should be true Test: TestSyncBackupDir Messages: Unexpected file "dst/three.txt" fstest.go:194: Not found "backup/one" fstest.go:194: Not found "backup/three.txt" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1587 sync_test.go:1614 Error: Not equal: expected: 0 actual : 2 Test: TestSyncBackupDir Messages: 2 objects not found fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1593 sync_test.go:1614 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (3), backup/three.txt (5), dst/one (4), dst/three.txt (6), dst/two (3) got dst/one (4), dst/three.txt (6), dst/two (3) fstest.go:194: Not found "backup/one" fstest.go:194: Not found "backup/three.txt" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1593 sync_test.go:1614 Error: Not equal: expected: 0 actual : 2 Test: TestSyncBackupDir Messages: 2 objects not found fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1612 sync_test.go:1614 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (4), backup/three.txt (6), dst/one (5), dst/two (3) got dst/one (5), dst/three.txt (6), dst/two (3) fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1612 sync_test.go:1614 Error: Should be true Test: TestSyncBackupDir Messages: Unexpected file "dst/three.txt" fstest.go:194: Not found "backup/three.txt" fstest.go:194: Not found "backup/one" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1612 sync_test.go:1614 Error: Not equal: expected: 0 actual : 2 Test: TestSyncBackupDir Messages: 2 objects not found === RUN TestSyncBackupDirWithSuffix 2020/04/15 07:04:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/04/15 07:04:43 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4/dst': Waiting for checks to finish 2020/04/15 07:04:43 INFO : one: Moved (server side) 2020/04/15 07:04:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/04/15 07:04:43 DEBUG : two: Unchanged skipping 2020/04/15 07:04:43 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4/dst': Waiting for transfers to finish 2020/04/15 07:04:43 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/04/15 07:04:43 INFO : one: Copied (new) 2020/04/15 07:04:43 DEBUG : Waiting for deletions to finish 2020/04/15 07:04:43 INFO : three.txt: Moved (server side) 2020/04/15 07:04:43 INFO : three.txt: Moved into backup dir 2020/04/15 07:06:49 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4/dst': Waiting for checks to finish 2020/04/15 07:06:49 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/04/15 07:06:49 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/04/15 07:06:49 DEBUG : two: Unchanged skipping 2020/04/15 07:06:49 INFO : one: Moved (server side) 2020/04/15 07:06:49 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4/dst': Waiting for transfers to finish 2020/04/15 07:06:49 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/04/15 07:06:49 INFO : one: Copied (new) 2020/04/15 07:06:49 DEBUG : Waiting for deletions to finish 2020/04/15 07:06:49 INFO : three.txt.bak: Moved (server side) 2020/04/15 07:06:49 INFO : three.txt.bak: Moved into backup dir 2020/04/15 07:06:49 INFO : three.txt: Moved (server side) 2020/04/15 07:06:49 INFO : three.txt: Moved into backup dir 2020/04/15 07:06:49 INFO : one.bak: Moved (server side) 2020/04/15 07:06:49 INFO : one.bak: Moved into backup dir --- FAIL: TestSyncBackupDirWithSuffix (189.05s) run.go:176: Remote "union root 'rclone-test-patocux3quhomuz8velohub4'", Local "Local file system at /tmp/rclone903742308", Modify Window "1ns" fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1587 sync_test.go:1615 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want backup/one.bak (3), backup/three.txt.bak (5), dst/one (4), dst/two (3) got dst/one (4), dst/one.bak (3), dst/three.txt.bak (5), dst/two (3) fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1587 sync_test.go:1615 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: Unexpected file "dst/one.bak" fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1587 sync_test.go:1615 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: Unexpected file "dst/three.txt.bak" fstest.go:194: Not found "backup/one.bak" fstest.go:194: Not found "backup/three.txt.bak" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1587 sync_test.go:1615 Error: Not equal: expected: 0 actual : 2 Test: TestSyncBackupDirWithSuffix Messages: 2 objects not found fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1593 sync_test.go:1615 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want backup/one.bak (3), backup/three.txt.bak (5), dst/one (4), dst/three.txt (6), dst/two (3) got dst/one (4), dst/one.bak (3), dst/three.txt (6), dst/three.txt.bak (5), dst/two (3) fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1593 sync_test.go:1615 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: Unexpected file "dst/one.bak" fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1593 sync_test.go:1615 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: Unexpected file "dst/three.txt.bak" fstest.go:194: Not found "backup/one.bak" fstest.go:194: Not found "backup/three.txt.bak" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1593 sync_test.go:1615 Error: Not equal: expected: 0 actual : 2 Test: TestSyncBackupDirWithSuffix Messages: 2 objects not found fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1612 sync_test.go:1615 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want backup/one.bak (4), backup/three.txt.bak (6), dst/one (5), dst/two (3) got dst/one (5), dst/one.bak.bak (4), dst/three.txt.bak (6), dst/three.txt.bak.bak (5), dst/two (3) fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1612 sync_test.go:1615 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: Unexpected file "dst/one.bak.bak" fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1612 sync_test.go:1615 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: Unexpected file "dst/three.txt.bak" fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1612 sync_test.go:1615 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: Unexpected file "dst/three.txt.bak.bak" fstest.go:194: Not found "backup/three.txt.bak" fstest.go:194: Not found "backup/one.bak" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1612 sync_test.go:1615 Error: Not equal: expected: 0 actual : 2 Test: TestSyncBackupDirWithSuffix Messages: 2 objects not found === RUN TestSyncBackupDirWithSuffixKeepExtension 2020/04/15 07:07:52 DEBUG : one: Sizes differ (src 4 vs dst 3) 2020/04/15 07:07:52 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4/dst': Waiting for checks to finish 2020/04/15 07:07:52 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/04/15 07:07:52 DEBUG : two: Unchanged skipping 2020/04/15 07:07:52 INFO : one: Moved (server side) 2020/04/15 07:07:52 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4/dst': Waiting for transfers to finish 2020/04/15 07:07:52 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2020/04/15 07:07:52 INFO : one: Copied (new) 2020/04/15 07:07:52 DEBUG : Waiting for deletions to finish 2020/04/15 07:07:52 INFO : three.txt: Moved (server side) 2020/04/15 07:07:52 INFO : three.txt: Moved into backup dir 2020/04/15 07:09:58 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4/dst': Waiting for checks to finish 2020/04/15 07:09:58 DEBUG : one: Sizes differ (src 5 vs dst 4) 2020/04/15 07:09:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/04/15 07:09:58 DEBUG : two: Unchanged skipping 2020/04/15 07:09:58 INFO : one: Moved (server side) 2020/04/15 07:09:58 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4/dst': Waiting for transfers to finish 2020/04/15 07:09:58 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2020/04/15 07:09:58 INFO : one: Copied (new) 2020/04/15 07:09:58 DEBUG : Waiting for deletions to finish 2020/04/15 07:09:58 INFO : three.txt: Moved (server side) 2020/04/15 07:09:58 INFO : three.txt: Moved into backup dir 2020/04/15 07:09:58 INFO : three-2019-01-01.txt: Moved (server side) 2020/04/15 07:09:58 INFO : three-2019-01-01.txt: Moved into backup dir 2020/04/15 07:09:58 INFO : one-2019-01-01: Moved (server side) 2020/04/15 07:09:58 INFO : one-2019-01-01: Moved into backup dir --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (189.05s) run.go:176: Remote "union root 'rclone-test-patocux3quhomuz8velohub4'", Local "Local file system at /tmp/rclone903742308", Modify Window "1ns" fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1587 sync_test.go:1617 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (3), backup/three-2019-01-01.txt (5), dst/one (4), dst/two (3) got dst/one (4), dst/one-2019-01-01 (3), dst/three-2019-01-01.txt (5), dst/two (3) fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1587 sync_test.go:1617 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: Unexpected file "dst/one-2019-01-01" fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1587 sync_test.go:1617 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: Unexpected file "dst/three-2019-01-01.txt" fstest.go:194: Not found "backup/one-2019-01-01" fstest.go:194: Not found "backup/three-2019-01-01.txt" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1587 sync_test.go:1617 Error: Not equal: expected: 0 actual : 2 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 2 objects not found fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1593 sync_test.go:1617 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (3), backup/three-2019-01-01.txt (5), dst/one (4), dst/three.txt (6), dst/two (3) got dst/one (4), dst/one-2019-01-01 (3), dst/three-2019-01-01.txt (5), dst/three.txt (6), dst/two (3) fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1593 sync_test.go:1617 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: Unexpected file "dst/one-2019-01-01" fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1593 sync_test.go:1617 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: Unexpected file "dst/three-2019-01-01.txt" fstest.go:194: Not found "backup/one-2019-01-01" fstest.go:194: Not found "backup/three-2019-01-01.txt" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1593 sync_test.go:1617 Error: Not equal: expected: 0 actual : 2 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 2 objects not found fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:289: Sleeping for 2s for list eventual consistency: 2/6 fstest.go:289: Sleeping for 4s for list eventual consistency: 3/6 fstest.go:289: Sleeping for 8s for list eventual consistency: 4/6 fstest.go:289: Sleeping for 16s for list eventual consistency: 5/6 fstest.go:289: Sleeping for 32s for list eventual consistency: 6/6 fstest.go:296: Error Trace: fstest.go:296 fstest.go:329 fstest.go:341 sync_test.go:1612 sync_test.go:1617 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (4), backup/three-2019-01-01.txt (6), dst/one (5), dst/two (3) got dst/one (5), dst/one-2019-01-01-2019-01-01 (4), dst/three-2019-01-01-2019-01-01.txt (6), dst/two (3) fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1612 sync_test.go:1617 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: Unexpected file "dst/one-2019-01-01-2019-01-01" fstest.go:182: Error Trace: fstest.go:182 fstest.go:299 fstest.go:329 fstest.go:341 sync_test.go:1612 sync_test.go:1617 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: Unexpected file "dst/three-2019-01-01-2019-01-01.txt" fstest.go:194: Not found "backup/three-2019-01-01.txt" fstest.go:194: Not found "backup/one-2019-01-01" fstest.go:197: Error Trace: fstest.go:197 fstest.go:301 fstest.go:329 fstest.go:341 sync_test.go:1612 sync_test.go:1617 Error: Not equal: expected: 0 actual : 2 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 2 objects not found FAIL 2020/04/15 07:11:01 DEBUG : union root 'rclone-test-patocux3quhomuz8velohub4': Purge remote "./sync.test -test.v -test.timeout 30m0s -remote TestUnion: -verbose -test.run '^(TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension)$'" - Finished ERROR in 19m57.294303176s (try 5/5): exit status 1: Failed [TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension]